builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-055 starttime: 1474121960.41 results: success (0) buildid: 20160917004009 builduid: 39c0864104644d618a468a713b19f1a0 revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:20.415105) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:20.415547) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:20.415821) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:20.454582) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-17 07:19:20.454900) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 07:19:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 111M=0s 2016-09-17 07:19:20 (111 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104385 ========= master_lag: 2.58 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-17 07:19:23.138618) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:23.144971) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018715 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:23.176294) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:23.176657) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b9499912b32324acd84792bf02b533b40e98e496 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b9499912b32324acd84792bf02b533b40e98e496 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 07:19:23,298 truncating revision to first 12 chars 2016-09-17 07:19:23,298 Setting DEBUG logging. 2016-09-17 07:19:23,298 attempt 1/10 2016-09-17 07:19:23,298 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b9499912b323?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 07:19:23,617 unpacking tar archive at: mozilla-aurora-b9499912b323/testing/mozharness/ program finished with exit code 0 elapsedTime=0.653365 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:23.853440) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:23.853745) ========= script_repo_revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:23.858180) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:23.858478) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:23.871091) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-17 07:19:23.871411) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:19:24 INFO - MultiFileLogger online at 20160917 07:19:24 in /builds/slave/test 07:19:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:19:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:19:24 INFO - {'append_to_log': False, 07:19:24 INFO - 'base_work_dir': '/builds/slave/test', 07:19:24 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:19:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:19:24 INFO - 'branch': 'Mozilla-Aurora', 07:19:24 INFO - 'buildbot_json_path': 'buildprops.json', 07:19:24 INFO - 'config_files': ('talos/linux_config.py',), 07:19:24 INFO - 'default_actions': ('clobber', 07:19:24 INFO - 'read-buildbot-config', 07:19:24 INFO - 'download-and-extract', 07:19:24 INFO - 'populate-webroot', 07:19:24 INFO - 'create-virtualenv', 07:19:24 INFO - 'install', 07:19:24 INFO - 'run-tests'), 07:19:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:19:24 INFO - 'download_minidump_stackwalk': True, 07:19:24 INFO - 'download_symbols': 'ondemand', 07:19:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:19:24 INFO - 'tooltool.py': '/tools/tooltool.py', 07:19:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:19:24 INFO - '/tools/misc-python/virtualenv.py')}, 07:19:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:19:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:19:24 INFO - 'installer_path': 'installer.exe', 07:19:24 INFO - 'log_level': 'info', 07:19:24 INFO - 'log_name': 'talos', 07:19:24 INFO - 'log_to_console': True, 07:19:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:19:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:19:24 INFO - 'opt_config_files': (), 07:19:24 INFO - 'pip_index': False, 07:19:24 INFO - 'sps_profile': False, 07:19:24 INFO - 'sps_profile_interval': 0, 07:19:24 INFO - 'suite': 'dromaeojs', 07:19:24 INFO - 'system_bits': '32', 07:19:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:19:24 INFO - 'title': 'talos-linux64-ix-055', 07:19:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:19:24 INFO - 'use_talos_json': True, 07:19:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:19:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:19:24 INFO - 'work_dir': 'build'} 07:19:24 INFO - [mozharness: 2016-09-17 14:19:24.075469Z] Running clobber step. 07:19:24 INFO - Running pre-action listener: _resource_record_pre_action 07:19:24 INFO - Running main action method: clobber 07:19:24 INFO - rmtree: /builds/slave/test/build 07:19:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:19:24 INFO - Running post-action listener: _resource_record_post_action 07:19:24 INFO - [mozharness: 2016-09-17 14:19:24.535070Z] Finished clobber step (success) 07:19:24 INFO - [mozharness: 2016-09-17 14:19:24.535172Z] Running read-buildbot-config step. 07:19:24 INFO - Running pre-action listener: _resource_record_pre_action 07:19:24 INFO - Running main action method: read_buildbot_config 07:19:24 INFO - Using buildbot properties: 07:19:24 INFO - { 07:19:24 INFO - "project": "", 07:19:24 INFO - "product": "firefox", 07:19:24 INFO - "script_repo_revision": "production", 07:19:24 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 07:19:24 INFO - "repository": "", 07:19:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 07:19:24 INFO - "buildid": "20160917004009", 07:19:24 INFO - "stage_platform": "linux64-pgo", 07:19:24 INFO - "basedir": "/builds/slave/test", 07:19:24 INFO - "nightly_build": "True", 07:19:24 INFO - "buildnumber": 473, 07:19:24 INFO - "slavename": "talos-linux64-ix-055", 07:19:24 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:19:24 INFO - "platform": "ubuntu64_hw", 07:19:24 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 07:19:24 INFO - "branch": "mozilla-aurora", 07:19:24 INFO - "repo_path": "releases/mozilla-aurora", 07:19:24 INFO - "revision": "b9499912b32324acd84792bf02b533b40e98e496", 07:19:24 INFO - "pgo_build": "True", 07:19:24 INFO - "builduid": "39c0864104644d618a468a713b19f1a0", 07:19:24 INFO - "slavebuilddir": "test" 07:19:24 INFO - } 07:19:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 07:19:24 INFO - Running post-action listener: _resource_record_post_action 07:19:24 INFO - [mozharness: 2016-09-17 14:19:24.537671Z] Finished read-buildbot-config step (success) 07:19:24 INFO - [mozharness: 2016-09-17 14:19:24.537760Z] Running download-and-extract step. 07:19:24 INFO - Running pre-action listener: _resource_record_pre_action 07:19:24 INFO - Running main action method: download_and_extract 07:19:24 INFO - mkdir: /builds/slave/test/build/tests 07:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:19:24 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:19:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:19:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:19:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:19:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:19:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:19:24 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:19:24 INFO - retry: Failed, sleeping 30 seconds before retrying 07:19:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 07:19:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:19:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:19:54 INFO - retry: Failed, sleeping 60 seconds before retrying 07:20:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 07:20:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:20:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:20:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 07:20:54 INFO - Caught exception: HTTP Error 404: Not Found 07:20:54 INFO - Caught exception: HTTP Error 404: Not Found 07:20:54 INFO - Caught exception: HTTP Error 404: Not Found 07:20:54 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:20:54 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:20:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:20:55 INFO - Downloaded 1361 bytes. 07:20:55 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:20:55 INFO - Using the following test package requirements: 07:20:55 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 07:20:55 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:20:55 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 07:20:55 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:20:55 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 07:20:55 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:20:55 INFO - u'jsshell-linux-x86_64.zip'], 07:20:55 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:20:55 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 07:20:55 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 07:20:55 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:20:55 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 07:20:55 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:20:55 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 07:20:55 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:20:55 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 07:20:55 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:20:55 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 07:20:55 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:20:55 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:20:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:20:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:20:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:20:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:20:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:20:55 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:20:55 INFO - retry: Failed, sleeping 30 seconds before retrying 07:21:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 07:21:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:21:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:21:25 INFO - retry: Failed, sleeping 60 seconds before retrying 07:22:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 07:22:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:25 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:22:25 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 07:22:25 INFO - Caught exception: HTTP Error 404: Not Found 07:22:25 INFO - Caught exception: HTTP Error 404: Not Found 07:22:25 INFO - Caught exception: HTTP Error 404: Not Found 07:22:25 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:25 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:22:26 INFO - Downloaded 21771059 bytes. 07:22:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:22:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:22:27 INFO - Return code: 0 07:22:27 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:22:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:22:27 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:22:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:22:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:22:27 INFO - retry: Failed, sleeping 30 seconds before retrying 07:22:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 07:22:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:57 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:22:57 INFO - retry: Failed, sleeping 60 seconds before retrying 07:23:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 07:23:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:23:57 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:23:57 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 07:23:57 INFO - Caught exception: HTTP Error 404: Not Found 07:23:57 INFO - Caught exception: HTTP Error 404: Not Found 07:23:57 INFO - Caught exception: HTTP Error 404: Not Found 07:23:57 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:23:57 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:23:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:23:58 INFO - Downloaded 21771059 bytes. 07:23:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:23:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:23:59 INFO - Return code: 0 07:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:23:59 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:23:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:23:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:23:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:23:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:23:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:23:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:23:59 INFO - retry: Failed, sleeping 30 seconds before retrying 07:24:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 07:24:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:24:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:24:29 INFO - retry: Failed, sleeping 60 seconds before retrying 07:25:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 07:25:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:25:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:25:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 07:25:29 INFO - Caught exception: HTTP Error 404: Not Found 07:25:29 INFO - Caught exception: HTTP Error 404: Not Found 07:25:29 INFO - Caught exception: HTTP Error 404: Not Found 07:25:29 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:25:29 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:25:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:25:30 INFO - Downloaded 14413740 bytes. 07:25:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:25:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 07:25:30 INFO - Return code: 0 07:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:30 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:25:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:25:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:25:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:25:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:25:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:25:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:25:30 INFO - retry: Failed, sleeping 30 seconds before retrying 07:26:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 07:26:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:26:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:26:00 INFO - retry: Failed, sleeping 60 seconds before retrying 07:27:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 07:27:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:27:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:27:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 07:27:00 INFO - Caught exception: HTTP Error 404: Not Found 07:27:00 INFO - Caught exception: HTTP Error 404: Not Found 07:27:00 INFO - Caught exception: HTTP Error 404: Not Found 07:27:00 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:27:00 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:27:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:27:02 INFO - Downloaded 63891050 bytes. 07:27:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:27:02 INFO - mkdir: /builds/slave/test/properties 07:27:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:27:02 INFO - Writing to file /builds/slave/test/properties/build_url 07:27:02 INFO - Contents: 07:27:02 INFO - build_url:https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:27:02 INFO - Running post-action listener: _resource_record_post_action 07:27:02 INFO - Running post-action listener: set_extra_try_arguments 07:27:02 INFO - [mozharness: 2016-09-17 14:27:02.857546Z] Finished download-and-extract step (success) 07:27:02 INFO - [mozharness: 2016-09-17 14:27:02.857781Z] Running populate-webroot step. 07:27:02 INFO - Running pre-action listener: _resource_record_pre_action 07:27:02 INFO - Running main action method: populate_webroot 07:27:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:27:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:27:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:27:02 INFO - '--tppagecycles', 07:27:02 INFO - '7'], 07:27:02 INFO - 'tests': ['tsvgm']}}, 07:27:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:27:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:27:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:27:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:27:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:27:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:27:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:02 INFO - 'talos_options': ['--disable-e10s'], 07:27:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:27:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:27:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:02 INFO - 'talos_options': ['--disable-e10s'], 07:27:02 INFO - 'tests': ['damp', 'tps']}, 07:27:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:02 INFO - 'tests': ['damp', 'tps']}, 07:27:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:27:02 INFO - 'tests': ['dromaeo_dom']}, 07:27:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:27:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:27:02 INFO - 'tests': ['basic_compositor_video']}, 07:27:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:27:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:27:02 INFO - 'tests': ['a11yr', 07:27:02 INFO - 'ts_paint', 07:27:02 INFO - 'tpaint', 07:27:02 INFO - 'sessionrestore', 07:27:02 INFO - 'sessionrestore_no_auto_restore', 07:27:02 INFO - 'tabpaint']}, 07:27:02 INFO - 'other-e10s': {'tests': ['a11yr', 07:27:02 INFO - 'ts_paint', 07:27:02 INFO - 'tpaint', 07:27:02 INFO - 'sessionrestore', 07:27:02 INFO - 'sessionrestore_no_auto_restore', 07:27:02 INFO - 'tabpaint']}, 07:27:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:27:02 INFO - 'ts_paint', 07:27:02 INFO - 'tpaint', 07:27:02 INFO - 'sessionrestore', 07:27:02 INFO - 'sessionrestore_no_auto_restore', 07:27:02 INFO - 'tabpaint']}, 07:27:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:27:02 INFO - 'ts_paint', 07:27:02 INFO - 'tpaint', 07:27:02 INFO - 'sessionrestore', 07:27:02 INFO - 'sessionrestore_no_auto_restore', 07:27:02 INFO - 'tabpaint']}, 07:27:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:27:02 INFO - 'tests': ['a11yr', 07:27:02 INFO - 'ts_paint', 07:27:02 INFO - 'tpaint', 07:27:02 INFO - 'sessionrestore', 07:27:02 INFO - 'sessionrestore_no_auto_restore', 07:27:02 INFO - 'tabpaint']}, 07:27:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:27:02 INFO - 'tests': ['a11yr', 07:27:02 INFO - 'ts_paint', 07:27:02 INFO - 'tpaint', 07:27:02 INFO - 'sessionrestore', 07:27:02 INFO - 'sessionrestore_no_auto_restore', 07:27:02 INFO - 'tabpaint']}, 07:27:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:27:02 INFO - 'tests': ['tsvgx', 07:27:02 INFO - 'tsvgr_opacity', 07:27:02 INFO - 'tart', 07:27:02 INFO - 'tscrollx', 07:27:02 INFO - 'cart']}, 07:27:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:27:02 INFO - 'tsvgr_opacity', 07:27:02 INFO - 'tart', 07:27:02 INFO - 'tscrollx', 07:27:02 INFO - 'cart']}, 07:27:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:02 INFO - 'talos_options': ['--disable-e10s'], 07:27:02 INFO - 'tests': ['tp5o']}, 07:27:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:02 INFO - 'tests': ['tp5o']}, 07:27:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:02 INFO - 'talos_options': ['--disable-e10s', 07:27:02 INFO - '--xperf_path', 07:27:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:27:02 INFO - 'tests': ['tp5n']}, 07:27:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:02 INFO - 'talos_options': ['--xperf_path', 07:27:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:27:02 INFO - 'tests': ['tp5n']}}, 07:27:02 INFO - 'talos.zip': {'path': '', 07:27:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:27:02 INFO - Running post-action listener: _resource_record_post_action 07:27:02 INFO - [mozharness: 2016-09-17 14:27:02.873861Z] Finished populate-webroot step (success) 07:27:02 INFO - [mozharness: 2016-09-17 14:27:02.873968Z] Running create-virtualenv step. 07:27:02 INFO - Running pre-action listener: _resource_record_pre_action 07:27:02 INFO - Running main action method: create_virtualenv 07:27:02 INFO - Creating virtualenv /builds/slave/test/build/venv 07:27:02 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:27:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:27:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:27:02 INFO - Using real prefix '/usr' 07:27:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:27:03 INFO - Installing distribute.............................................................................................................................................................................................done. 07:27:05 INFO - Installing pip.................done. 07:27:05 INFO - Return code: 0 07:27:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1257180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12553b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1256740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:27:05 INFO - Using env: {'DISPLAY': ':0', 07:27:05 INFO - 'HOME': '/home/cltbld', 07:27:05 INFO - 'LANG': 'en_US.UTF-8', 07:27:05 INFO - 'LANGUAGE': 'en_US:en', 07:27:05 INFO - 'LOGNAME': 'cltbld', 07:27:05 INFO - 'MAIL': '/var/mail/cltbld', 07:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:05 INFO - 'MOZ_NO_REMOTE': '1', 07:27:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:05 INFO - 'NO_EM_RESTART': '1', 07:27:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:05 INFO - 'PWD': '/builds/slave/test', 07:27:05 INFO - 'SHELL': '/bin/bash', 07:27:05 INFO - 'SHLVL': '1', 07:27:05 INFO - 'TERM': 'linux', 07:27:05 INFO - 'TMOUT': '86400', 07:27:05 INFO - 'USER': 'cltbld', 07:27:05 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 07:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:05 INFO - '_': '/tools/buildbot/bin/python'} 07:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:27:05 INFO - Cleaning up... 07:27:05 INFO - Return code: 0 07:27:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1257180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12553b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1256740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:27:05 INFO - Using env: {'DISPLAY': ':0', 07:27:05 INFO - 'HOME': '/home/cltbld', 07:27:05 INFO - 'LANG': 'en_US.UTF-8', 07:27:05 INFO - 'LANGUAGE': 'en_US:en', 07:27:05 INFO - 'LOGNAME': 'cltbld', 07:27:05 INFO - 'MAIL': '/var/mail/cltbld', 07:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:05 INFO - 'MOZ_NO_REMOTE': '1', 07:27:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:05 INFO - 'NO_EM_RESTART': '1', 07:27:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:05 INFO - 'PWD': '/builds/slave/test', 07:27:05 INFO - 'SHELL': '/bin/bash', 07:27:05 INFO - 'SHLVL': '1', 07:27:05 INFO - 'TERM': 'linux', 07:27:05 INFO - 'TMOUT': '86400', 07:27:05 INFO - 'USER': 'cltbld', 07:27:05 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 07:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:05 INFO - '_': '/tools/buildbot/bin/python'} 07:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:05 INFO - Downloading/unpacking psutil>=3.1.1 07:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:27:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:27:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:27:07 INFO - Installing collected packages: psutil 07:27:07 INFO - Running setup.py install for psutil 07:27:07 INFO - building 'psutil._psutil_linux' extension 07:27:07 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 07:27:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:27:08 INFO - building 'psutil._psutil_posix' extension 07:27:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 07:27:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 07:27:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:27:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:27:08 INFO - Successfully installed psutil 07:27:08 INFO - Cleaning up... 07:27:08 INFO - Return code: 0 07:27:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1257180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12553b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1256740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:27:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:27:08 INFO - Using env: {'DISPLAY': ':0', 07:27:08 INFO - 'HOME': '/home/cltbld', 07:27:08 INFO - 'LANG': 'en_US.UTF-8', 07:27:08 INFO - 'LANGUAGE': 'en_US:en', 07:27:08 INFO - 'LOGNAME': 'cltbld', 07:27:08 INFO - 'MAIL': '/var/mail/cltbld', 07:27:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:08 INFO - 'MOZ_NO_REMOTE': '1', 07:27:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:08 INFO - 'NO_EM_RESTART': '1', 07:27:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:08 INFO - 'PWD': '/builds/slave/test', 07:27:08 INFO - 'SHELL': '/bin/bash', 07:27:08 INFO - 'SHLVL': '1', 07:27:08 INFO - 'TERM': 'linux', 07:27:08 INFO - 'TMOUT': '86400', 07:27:08 INFO - 'USER': 'cltbld', 07:27:08 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 07:27:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:08 INFO - '_': '/tools/buildbot/bin/python'} 07:27:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:27:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:27:10 INFO - Installing collected packages: mozsystemmonitor 07:27:10 INFO - Running setup.py install for mozsystemmonitor 07:27:10 INFO - Successfully installed mozsystemmonitor 07:27:10 INFO - Cleaning up... 07:27:10 INFO - Return code: 0 07:27:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1257180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12553b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1256740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:27:10 INFO - Using env: {'DISPLAY': ':0', 07:27:10 INFO - 'HOME': '/home/cltbld', 07:27:10 INFO - 'LANG': 'en_US.UTF-8', 07:27:10 INFO - 'LANGUAGE': 'en_US:en', 07:27:10 INFO - 'LOGNAME': 'cltbld', 07:27:10 INFO - 'MAIL': '/var/mail/cltbld', 07:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:10 INFO - 'MOZ_NO_REMOTE': '1', 07:27:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:10 INFO - 'NO_EM_RESTART': '1', 07:27:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:10 INFO - 'PWD': '/builds/slave/test', 07:27:10 INFO - 'SHELL': '/bin/bash', 07:27:10 INFO - 'SHLVL': '1', 07:27:10 INFO - 'TERM': 'linux', 07:27:10 INFO - 'TMOUT': '86400', 07:27:10 INFO - 'USER': 'cltbld', 07:27:10 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 07:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:10 INFO - '_': '/tools/buildbot/bin/python'} 07:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:10 INFO - Downloading/unpacking jsonschema==2.5.1 07:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:27:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:12 INFO - Downloading functools32-3.2.3-2.tar.gz 07:27:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:27:13 INFO - warning: no files found matching '*.txt' 07:27:13 INFO - no previously-included directories found matching 'build' 07:27:13 INFO - no previously-included directories found matching 'dist' 07:27:13 INFO - no previously-included directories found matching '.git*' 07:27:13 INFO - Installing collected packages: jsonschema, functools32 07:27:13 INFO - Running setup.py install for functools32 07:27:13 INFO - warning: no files found matching '*.txt' 07:27:13 INFO - no previously-included directories found matching 'build' 07:27:13 INFO - no previously-included directories found matching 'dist' 07:27:13 INFO - no previously-included directories found matching '.git*' 07:27:13 INFO - Successfully installed jsonschema functools32 07:27:13 INFO - Cleaning up... 07:27:13 INFO - Return code: 0 07:27:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:27:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1257180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12553b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1256740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:27:13 INFO - Using env: {'DISPLAY': ':0', 07:27:13 INFO - 'HOME': '/home/cltbld', 07:27:13 INFO - 'LANG': 'en_US.UTF-8', 07:27:13 INFO - 'LANGUAGE': 'en_US:en', 07:27:13 INFO - 'LOGNAME': 'cltbld', 07:27:13 INFO - 'MAIL': '/var/mail/cltbld', 07:27:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:13 INFO - 'MOZ_NO_REMOTE': '1', 07:27:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:13 INFO - 'NO_EM_RESTART': '1', 07:27:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:13 INFO - 'PWD': '/builds/slave/test', 07:27:13 INFO - 'SHELL': '/bin/bash', 07:27:13 INFO - 'SHLVL': '1', 07:27:13 INFO - 'TERM': 'linux', 07:27:13 INFO - 'TMOUT': '86400', 07:27:13 INFO - 'USER': 'cltbld', 07:27:13 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 07:27:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:13 INFO - '_': '/tools/buildbot/bin/python'} 07:27:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:27:13 INFO - Cleaning up... 07:27:13 INFO - Return code: 0 07:27:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:27:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1257180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12553b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1256740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:27:13 INFO - Using env: {'DISPLAY': ':0', 07:27:13 INFO - 'HOME': '/home/cltbld', 07:27:13 INFO - 'LANG': 'en_US.UTF-8', 07:27:13 INFO - 'LANGUAGE': 'en_US:en', 07:27:13 INFO - 'LOGNAME': 'cltbld', 07:27:13 INFO - 'MAIL': '/var/mail/cltbld', 07:27:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:13 INFO - 'MOZ_NO_REMOTE': '1', 07:27:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:13 INFO - 'NO_EM_RESTART': '1', 07:27:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:13 INFO - 'PWD': '/builds/slave/test', 07:27:13 INFO - 'SHELL': '/bin/bash', 07:27:13 INFO - 'SHLVL': '1', 07:27:13 INFO - 'TERM': 'linux', 07:27:13 INFO - 'TMOUT': '86400', 07:27:13 INFO - 'USER': 'cltbld', 07:27:13 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 07:27:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:13 INFO - '_': '/tools/buildbot/bin/python'} 07:27:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:13 INFO - Downloading/unpacking blobuploader==1.2.4 07:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:15 INFO - Downloading blobuploader-1.2.4.tar.gz 07:27:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:27:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:27:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:16 INFO - Downloading docopt-0.6.1.tar.gz 07:27:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:27:16 INFO - Installing collected packages: blobuploader, requests, docopt 07:27:16 INFO - Running setup.py install for blobuploader 07:27:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:27:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:27:16 INFO - Running setup.py install for requests 07:27:16 INFO - Running setup.py install for docopt 07:27:16 INFO - Successfully installed blobuploader requests docopt 07:27:16 INFO - Cleaning up... 07:27:16 INFO - Return code: 0 07:27:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1257180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12553b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1256740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:27:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:27:16 INFO - Using env: {'DISPLAY': ':0', 07:27:16 INFO - 'HOME': '/home/cltbld', 07:27:16 INFO - 'LANG': 'en_US.UTF-8', 07:27:16 INFO - 'LANGUAGE': 'en_US:en', 07:27:16 INFO - 'LOGNAME': 'cltbld', 07:27:16 INFO - 'MAIL': '/var/mail/cltbld', 07:27:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:16 INFO - 'MOZ_NO_REMOTE': '1', 07:27:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:16 INFO - 'NO_EM_RESTART': '1', 07:27:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:16 INFO - 'PWD': '/builds/slave/test', 07:27:16 INFO - 'SHELL': '/bin/bash', 07:27:16 INFO - 'SHLVL': '1', 07:27:16 INFO - 'TERM': 'linux', 07:27:16 INFO - 'TMOUT': '86400', 07:27:16 INFO - 'USER': 'cltbld', 07:27:16 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 07:27:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:16 INFO - '_': '/tools/buildbot/bin/python'} 07:27:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:27:17 INFO - Running setup.py (path:/tmp/pip-jlzeQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:27:17 INFO - Running setup.py (path:/tmp/pip-4BFqRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:27:17 INFO - Running setup.py (path:/tmp/pip-Gu3YGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:27:17 INFO - Running setup.py (path:/tmp/pip-GaDzCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:27:17 INFO - Running setup.py (path:/tmp/pip-NPY0_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:27:17 INFO - Running setup.py (path:/tmp/pip-EaTIBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:27:17 INFO - Running setup.py (path:/tmp/pip-Ku8bnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:27:17 INFO - Running setup.py (path:/tmp/pip-noF7GW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:27:17 INFO - Running setup.py (path:/tmp/pip-SZqRqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:27:17 INFO - Running setup.py (path:/tmp/pip-fwBA8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:27:17 INFO - Running setup.py (path:/tmp/pip-r6hs4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:27:17 INFO - Running setup.py (path:/tmp/pip-U8FN6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:27:18 INFO - Running setup.py (path:/tmp/pip-n6imkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:27:18 INFO - Running setup.py (path:/tmp/pip-LmZig0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:18 INFO - Running setup.py (path:/tmp/pip-VfbV0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:27:18 INFO - Running setup.py (path:/tmp/pip-Dn_FDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:27:18 INFO - Running setup.py (path:/tmp/pip-vLihzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:27:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:27:18 INFO - Running setup.py install for manifestparser 07:27:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:27:18 INFO - Running setup.py install for mozcrash 07:27:18 INFO - Running setup.py install for mozdebug 07:27:18 INFO - Running setup.py install for mozdevice 07:27:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:27:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:27:18 INFO - Running setup.py install for mozfile 07:27:18 INFO - Running setup.py install for mozhttpd 07:27:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Running setup.py install for mozinfo 07:27:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Running setup.py install for mozInstall 07:27:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Running setup.py install for mozleak 07:27:19 INFO - Running setup.py install for mozlog 07:27:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Running setup.py install for moznetwork 07:27:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Running setup.py install for mozprocess 07:27:19 INFO - Running setup.py install for mozprofile 07:27:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Running setup.py install for mozrunner 07:27:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:27:19 INFO - Running setup.py install for mozscreenshot 07:27:19 INFO - Running setup.py install for moztest 07:27:20 INFO - Running setup.py install for mozversion 07:27:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:27:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:27:20 INFO - Cleaning up... 07:27:20 INFO - Return code: 0 07:27:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1257180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12553b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1256740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:27:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:27:20 INFO - Using env: {'DISPLAY': ':0', 07:27:20 INFO - 'HOME': '/home/cltbld', 07:27:20 INFO - 'LANG': 'en_US.UTF-8', 07:27:20 INFO - 'LANGUAGE': 'en_US:en', 07:27:20 INFO - 'LOGNAME': 'cltbld', 07:27:20 INFO - 'MAIL': '/var/mail/cltbld', 07:27:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:20 INFO - 'MOZ_NO_REMOTE': '1', 07:27:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:20 INFO - 'NO_EM_RESTART': '1', 07:27:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:20 INFO - 'PWD': '/builds/slave/test', 07:27:20 INFO - 'SHELL': '/bin/bash', 07:27:20 INFO - 'SHLVL': '1', 07:27:20 INFO - 'TERM': 'linux', 07:27:20 INFO - 'TMOUT': '86400', 07:27:20 INFO - 'USER': 'cltbld', 07:27:20 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 07:27:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:20 INFO - '_': '/tools/buildbot/bin/python'} 07:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:27:20 INFO - Running setup.py (path:/tmp/pip-MXj8Pv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:27:20 INFO - Running setup.py (path:/tmp/pip-8szbjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:27:20 INFO - Running setup.py (path:/tmp/pip-s2dRfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:27:20 INFO - Running setup.py (path:/tmp/pip-TJjtBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:27:20 INFO - Running setup.py (path:/tmp/pip-FO6FPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:27:20 INFO - Running setup.py (path:/tmp/pip-fxQ4MF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:27:20 INFO - Running setup.py (path:/tmp/pip-FvpfJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:27:20 INFO - Running setup.py (path:/tmp/pip-97bwFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:27:20 INFO - Running setup.py (path:/tmp/pip-oUUIR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:27:21 INFO - Running setup.py (path:/tmp/pip-BiBVOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:27:21 INFO - Running setup.py (path:/tmp/pip-VMHkgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:27:21 INFO - Running setup.py (path:/tmp/pip-AVmLgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:27:21 INFO - Running setup.py (path:/tmp/pip-2CsHUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:27:21 INFO - Running setup.py (path:/tmp/pip-p3uChi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:21 INFO - Running setup.py (path:/tmp/pip-93lWkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:27:21 INFO - Running setup.py (path:/tmp/pip-06Dhb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:27:21 INFO - Running setup.py (path:/tmp/pip-ACqD0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:27:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:27:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:23 INFO - Downloading blessings-1.6.tar.gz 07:27:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:27:23 INFO - Installing collected packages: blessings 07:27:23 INFO - Running setup.py install for blessings 07:27:23 INFO - Successfully installed blessings 07:27:23 INFO - Cleaning up... 07:27:23 INFO - Return code: 0 07:27:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:27:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:27:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:27:24 INFO - Reading from file tmpfile_stdout 07:27:24 INFO - Current package versions: 07:27:24 INFO - argparse == 1.2.1 07:27:24 INFO - blessings == 1.6 07:27:24 INFO - blobuploader == 1.2.4 07:27:24 INFO - docopt == 0.6.1 07:27:24 INFO - functools32 == 3.2.3-2 07:27:24 INFO - jsonschema == 2.5.1 07:27:24 INFO - manifestparser == 1.1 07:27:24 INFO - mozInstall == 1.12 07:27:24 INFO - mozcrash == 0.17 07:27:24 INFO - mozdebug == 0.1 07:27:24 INFO - mozdevice == 0.48 07:27:24 INFO - mozfile == 1.2 07:27:24 INFO - mozhttpd == 0.7 07:27:24 INFO - mozinfo == 0.9 07:27:24 INFO - mozleak == 0.1 07:27:24 INFO - mozlog == 3.3 07:27:24 INFO - moznetwork == 0.27 07:27:24 INFO - mozprocess == 0.23 07:27:24 INFO - mozprofile == 0.28 07:27:24 INFO - mozrunner == 6.12 07:27:24 INFO - mozscreenshot == 0.1 07:27:24 INFO - mozsystemmonitor == 0.3 07:27:24 INFO - moztest == 0.7 07:27:24 INFO - mozversion == 1.4 07:27:24 INFO - psutil == 3.1.1 07:27:24 INFO - requests == 1.2.3 07:27:24 INFO - wsgiref == 0.1.2 07:27:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1257180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12553b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1256740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:27:24 INFO - Using env: {'DISPLAY': ':0', 07:27:24 INFO - 'HOME': '/home/cltbld', 07:27:24 INFO - 'LANG': 'en_US.UTF-8', 07:27:24 INFO - 'LANGUAGE': 'en_US:en', 07:27:24 INFO - 'LOGNAME': 'cltbld', 07:27:24 INFO - 'MAIL': '/var/mail/cltbld', 07:27:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:24 INFO - 'MOZ_NO_REMOTE': '1', 07:27:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:24 INFO - 'NO_EM_RESTART': '1', 07:27:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:24 INFO - 'PWD': '/builds/slave/test', 07:27:24 INFO - 'SHELL': '/bin/bash', 07:27:24 INFO - 'SHLVL': '1', 07:27:24 INFO - 'TERM': 'linux', 07:27:24 INFO - 'TMOUT': '86400', 07:27:24 INFO - 'USER': 'cltbld', 07:27:24 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 07:27:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:24 INFO - '_': '/tools/buildbot/bin/python'} 07:27:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:27:24 INFO - Cleaning up... 07:27:24 INFO - Return code: 0 07:27:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:27:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf96030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1257180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12553b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1256740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:27:24 INFO - Using env: {'DISPLAY': ':0', 07:27:24 INFO - 'HOME': '/home/cltbld', 07:27:24 INFO - 'LANG': 'en_US.UTF-8', 07:27:24 INFO - 'LANGUAGE': 'en_US:en', 07:27:24 INFO - 'LOGNAME': 'cltbld', 07:27:24 INFO - 'MAIL': '/var/mail/cltbld', 07:27:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:24 INFO - 'MOZ_NO_REMOTE': '1', 07:27:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:24 INFO - 'NO_EM_RESTART': '1', 07:27:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:24 INFO - 'PWD': '/builds/slave/test', 07:27:24 INFO - 'SHELL': '/bin/bash', 07:27:24 INFO - 'SHLVL': '1', 07:27:24 INFO - 'TERM': 'linux', 07:27:24 INFO - 'TMOUT': '86400', 07:27:24 INFO - 'USER': 'cltbld', 07:27:24 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 07:27:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:24 INFO - '_': '/tools/buildbot/bin/python'} 07:27:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:27:24 INFO - Cleaning up... 07:27:24 INFO - Return code: 0 07:27:24 INFO - Running post-action listener: _resource_record_post_action 07:27:24 INFO - Running post-action listener: _start_resource_monitoring 07:27:24 INFO - Starting resource monitoring. 07:27:24 INFO - [mozharness: 2016-09-17 14:27:24.480375Z] Finished create-virtualenv step (success) 07:27:24 INFO - [mozharness: 2016-09-17 14:27:24.480805Z] Running install step. 07:27:24 INFO - Running pre-action listener: _resource_record_pre_action 07:27:24 INFO - Running main action method: install 07:27:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:27:24 INFO - Reading from file tmpfile_stdout 07:27:24 INFO - Detecting whether we're running mozinstall >=1.0... 07:27:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:27:24 INFO - Reading from file tmpfile_stdout 07:27:24 INFO - Output received: 07:27:24 INFO - Usage: mozinstall [options] installer 07:27:24 INFO - Options: 07:27:24 INFO - -h, --help show this help message and exit 07:27:24 INFO - -d DEST, --destination=DEST 07:27:24 INFO - Directory to install application into. [default: 07:27:24 INFO - "/builds/slave/test"] 07:27:24 INFO - --app=APP Application being installed. [default: firefox] 07:27:24 INFO - mkdir: /builds/slave/test/build/application 07:27:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:27:40 INFO - Reading from file tmpfile_stdout 07:27:40 INFO - Output received: 07:27:40 INFO - /builds/slave/test/build/application/firefox/firefox 07:27:40 INFO - Running post-action listener: _resource_record_post_action 07:27:40 INFO - [mozharness: 2016-09-17 14:27:40.786659Z] Finished install step (success) 07:27:40 INFO - [mozharness: 2016-09-17 14:27:40.786939Z] Running run-tests step. 07:27:40 INFO - Running pre-action listener: _resource_record_pre_action 07:27:40 INFO - Running main action method: run_tests 07:27:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:27:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:27:40 INFO - Python 2.7.3 07:27:40 INFO - Return code: 0 07:27:40 INFO - grabbing minidump binary from tooltool 07:27:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:40 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12553b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1256740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:27:40 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:27:40 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:27:40 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:27:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:27:40 INFO - Return code: 0 07:27:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:27:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:27:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:27:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:27:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:27:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:27:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:27:40 INFO - Using env: {'DISPLAY': ':0', 07:27:40 INFO - 'HOME': '/home/cltbld', 07:27:40 INFO - 'LANG': 'en_US.UTF-8', 07:27:40 INFO - 'LANGUAGE': 'en_US:en', 07:27:40 INFO - 'LOGNAME': 'cltbld', 07:27:40 INFO - 'MAIL': '/var/mail/cltbld', 07:27:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:27:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:27:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:40 INFO - 'MOZ_NO_REMOTE': '1', 07:27:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:27:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:40 INFO - 'NO_EM_RESTART': '1', 07:27:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:40 INFO - 'PWD': '/builds/slave/test', 07:27:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:27:40 INFO - 'SHELL': '/bin/bash', 07:27:40 INFO - 'SHLVL': '1', 07:27:40 INFO - 'TERM': 'linux', 07:27:40 INFO - 'TMOUT': '86400', 07:27:40 INFO - 'USER': 'cltbld', 07:27:40 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323', 07:27:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:40 INFO - '_': '/tools/buildbot/bin/python'} 07:27:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:27:41 INFO - mozversion application_buildid: 20160917004009 07:27:41 INFO - mozversion application_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:27:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:27:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:27:41 INFO - mozversion application_name: Firefox 07:27:41 INFO - mozversion application_remotingname: firefox-dev 07:27:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:27:41 INFO - mozversion application_vendor: Mozilla 07:27:41 INFO - mozversion application_version: 50.0a2 07:27:41 INFO - mozversion platform_buildid: 20160917004009 07:27:41 INFO - mozversion platform_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:27:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:27:41 INFO - mozversion platform_version: 50.0a2 07:27:41 INFO - using testdate: 1474122461 07:27:41 INFO - actual date: 1474122461 07:27:41 INFO - starting webserver on 'localhost:38011' 07:27:41 INFO - SUITE-START | Running 2 tests 07:27:41 INFO - TEST-START | dromaeo_css 07:27:41 INFO - operating with platform_type : linux_ 07:27:41 INFO - Initialising browser for dromaeo_css test... 07:27:41 INFO - TEST-INFO | started process 5515 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY1J5JH/profile http://localhost:38011/getInfo.html) 07:27:44 INFO - PROCESS | 5515 | __metrics Screen width/height:1600/1200 07:27:44 INFO - PROCESS | 5515 | colorDepth:24 07:27:44 INFO - PROCESS | 5515 | Browser inner width/height: 1024/697 07:27:44 INFO - PROCESS | 5515 | __metrics 07:27:45 INFO - PROCESS | 5515 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:27:45 INFO - PROCESS | 5515 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:27:46 INFO - TEST-INFO | 5515: exit 0 07:27:46 INFO - Browser initialized. 07:27:46 INFO - Running cycle 1/1 for dromaeo_css test... 07:27:46 INFO - TEST-INFO | started process 5605 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY1J5JH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:27:58 INFO - PROCESS | 5605 | RSS: Main: 146075648 07:27:58 INFO - PROCESS | 5605 | 07:28:49 INFO - PROCESS | 5605 | 1474122529144 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:30:58 INFO - PROCESS | 5605 | Cycle 1(1): loaded http://localhost:38011/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38011/tests/dromaeo/cssquery-ext.html) 07:30:59 INFO - PROCESS | 5605 | RSS: Main: 222810112 07:30:59 INFO - PROCESS | 5605 | 07:34:01 INFO - PROCESS | 5605 | Cycle 1(1): loaded http://localhost:38011/tests/dromaeo/cssquery-ext.html (next: http://localhost:38011/tests/dromaeo/cssquery-jquery.html) 07:34:02 INFO - PROCESS | 5605 | RSS: Main: 230105088 07:34:02 INFO - PROCESS | 5605 | 07:37:04 INFO - PROCESS | 5605 | Cycle 1(1): loaded http://localhost:38011/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38011/tests/dromaeo/cssquery-mootools.html) 07:37:04 INFO - PROCESS | 5605 | RSS: Main: 175087616 07:37:04 INFO - PROCESS | 5605 | 07:40:06 INFO - PROCESS | 5605 | Cycle 1(1): loaded http://localhost:38011/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38011/tests/dromaeo/cssquery-prototype.html) 07:40:07 INFO - PROCESS | 5605 | RSS: Main: 175693824 07:40:07 INFO - PROCESS | 5605 | 07:43:10 INFO - PROCESS | 5605 | Cycle 1(1): loaded http://localhost:38011/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38011/tests/dromaeo/cssquery-yui.html) 07:43:10 INFO - PROCESS | 5605 | RSS: Main: 173666304 07:43:10 INFO - PROCESS | 5605 | 07:46:13 INFO - PROCESS | 5605 | Cycle 1(1): loaded http://localhost:38011/tests/dromaeo/cssquery-yui.html (next: http://localhost:38011/tests/dromaeo/cssquery-dojo.html) 07:46:13 INFO - PROCESS | 5605 | RSS: Main: 265973760 07:46:13 INFO - PROCESS | 5605 | 07:46:13 INFO - PROCESS | 5605 | __start_tp_report 07:46:13 INFO - PROCESS | 5605 | _x_x_mozilla_page_load 07:46:13 INFO - PROCESS | 5605 | _x_x_mozilla_page_load_details 07:46:13 INFO - PROCESS | 5605 | |i|pagename|runs| 07:46:13 INFO - PROCESS | 5605 | |0;dojo.html;3210.887618933337;3220.8389580520966;3245.902622921312;3280.048785852103;3300.356430496053;5595.132754423066;5651.830445086649;5684.914726279109;5690.573207009481;5695.202671625976;5698.373178950323;5757.654540727559;5762.711864406773;5768.538516918647;5774.971125144369;5071.873203169914;5143.974280128594;5244.370675518933;5247.291615631898;5296.09966305729;5245.160774276116;5297.205419187114;5300.787968481256;5304.973475132619;5307.071262529062;5148.407933087687;5167.715775632339;5186.455422180673;5194.480551944813;5289.021531016755;5322.121849894763;5335.493128152858;5336.092864213093;5341.893162136736;5359;5020.49795020495;5102.744862756885;5122;5222.503862133091;5222.973885130588;5581.05122129239;5585.273914391144;5603.579731520153;5621.578381621394;5631;6098.481629061535;6098.634081955097;6101.2983506896835;6106.786262480836;6108.664023478706;6081.209442772455;6084.952386641381;6134.96932515339;6143.87712245757;6194.566380353377;5712.285964254468;5766.250387449689;5781.971090144522;5787.681677507693;5792.247007888947;6057.894501364603;6330.936690633035;6336.112944187817;6343.619382837044;6344.238691357066;5897.439743224387;5945.078512830518;5945.762169513171;6025.397460253939;6034.517238620988;6074.848128796815;6076.548599957207;6079.05774604937;6099.024156134997;6105.633661980295;5903.970480147572;6042.969785151046;6050.697465126814;6054.39456054391;6054.606450580698;6056.697165141725;6100.908486372708;6104.572679912363;6130.367562715059;6163.96918015407;4654.25531914892;4690.624750020059;4699.130660827689;4720.929186062212;4731.077439899255;4133.008078061303;4176.665866730655;4201.432806571135;4205.705600607989;4223.408722778813;5723.8569035774435;5724.026915424388;5794.652320860761;5879.088741245114;5890.322612899571;6125.908111378333;6142.04798256271;6145.477634401037;6147.0779383092;6151.876962460726;5824.825255242349;5845.590808643354;5848.736806843617;5851.210086638334;5853.502452291603;4823.493533178966;4886.877828054327;4901.705897646153;4915.311856340115;5001.64988450805;6325.746970121236;6353.110564520971;6356.968215158987;6357.11000459936;6379.011253255753;6127.571070025056;6147.162045276119;6157.568970172045;6170.5372097092;6182.598131121463;6169.50643948474;6208.751649934133;6226.750929962751;6271.749130034747;6351.333110023381;6037.667928263988;6043.576949613661;6044.546659000504;6051.788187413419;6086.969565152146;6162.106494558174;6163.229596300463;6170.197874276315;6170.352113028247;6174.413430724074;5903.675297858659;5932.7033648318275;5946.73239704223;5970.9701451492465;6068.605540639845;5960.672163030901;6010.33886272501;6011.338752737282;6015.939840601538;6024.939750602438;5917.585768996301;5947.167396564571;5954.910676339945;5993.460588547052;5995.340512543704;5927.8221653350465;5963.493103086286;6005.279366476051;6009.06859436788;6098.237720284965;5906.497947674496;5918.556108291809;5918.674472904032;5928.436798504135;5932 07:46:13 INFO - PROCESS | 5605 | |1;ext.html;594.4531031451064;613.6870196199937;634.7080343042203;653.1769969838006;659.3044338223178;34231.144221394476;37247.441288380665;37361.81319093401;46935.76532117335;47794.0441191176;47734.283985740156;48033;48074.75962620191;48127.75936120314;48157;9273.814523709538;9419.62321507139;9419.811603767921;9430.622775088988;9432.103950124727;8644.438111522762;10954.39750813705;11219.326840389562;11223.663290101269;11570.421478926062;11452.083833293316;11556.884431155708;11558.53765849365;11570.247933884313;11578.47896844644;11328;11362.431878406129;11397.715057123556;11407.08743300534;11414.086873050135;11272;11348.205625606211;11354.432278386048;11362;11367.488463019185;45381.09237815225;45554.40559580432;45590.31614525785;45594;45635.77182114102;4244.702870799046;4527.705699129546;4634.72191668501;4648.817576843784;4724.503927087675;35452.468212976826;35520.28959420797;35948;36873.81563092194;37195.62804371949;4459.44256967879;4539.818407263706;4556.931646025312;4567.903703111229;4682.438107427097;377951;379112;379657;380429;381280;38537.614623853966;64481.355186448476;64639.353606464276;64796;65010.67494662497;38712.419313710314;40270.59729402727;40335.19329613391;40546.797266013484;40602.390964135564;14308.213048282236;14383.928080359532;14391.136531808124;14392.85607143936;14434.639134021734;13680.110792798436;14938.925305373621;15019.173945433105;15133.848661513464;15156.09063456174;9043.050479699668;9165.81668366629;9217.723468295962;9235.1688348049;9317.068293170765;3446.293509830529;3511.613722490524;3513.3149035937745;3553.9111522211633;3594.0835087052633;1106.446776611694;1137.5677242647741;1163.3020187887369;1224.063591352616;1244.1912756708211;33167.3366532668;35189.24945132426;35294;35433.27950565205;35750.96116830046;1171.619223752284;1187.6615164678026;1213.7026428525069;1257.3587470389984;1272.5546058879318;18319.633607327778;18518.722219166724;18643.44069677912;18717.81282187161;18792.342268020555;613800;614966;619521;620083;620736;64402;64677.353226468076;64796.67601661962;64851;65069.349306507276;1352.2359866675297;1412.8022076909242;1460.8758255548184;1490.8285547162216;1496.9101853888583;34658.48012279868;37241.441378378804;37364.439533406476;37551;37580;54326.45673543372;54726.72636636791;54772.17841732457;54843.725781370835;54851.17723234234;407910;410299;411107;412866;413770;51173.48826511836;51493.227601585255;51654.741726291126;51778.74110629423;52396.21405678994;54199.45800541944;54549;54663.45336546743;55123;55228.723856380464;45034;47230;47610.285845713035;47847.76076119597;48304.516954830004;44783.32825007693;46276.30585541152;46539.76730116328;46855.76572117118;48220;46650.53349466462;46791.532084678714;47106.528934710215;48048.519514805805;48089.75955120202;38261.4260786094;41931;42185.57814421816;42243;42511.78744106383;31103.222419439695;31340.529892052095;31723.682763172073;32188.678113219506;32242 07:46:13 INFO - PROCESS | 5605 | |2;jquery.html;391.907901643114;426.86231996283107;428.8584767026881;439.5933761270825;442.7365717398147;16819.915900420496;21009.47476313093;21429.678554821672;21450.89274553625;21513.892430537864;19650.508737281576;20037.1985120595;20066.799332006674;20098.799012009913;20297.39107826764;5585.245991791117;5879.67661778602;5916.112583112525;5923.348431672513;5960.7317670704815;5751.281089863767;5898.1447690084915;5928.3775203603645;5931.436513531207;5932.2584676915385;5892;5921.496672782819;5923.28920529537;5927.051671732524;5934.317553481362;5724.427557244284;5809.82570522885;5898.439648233411;5904.261967254093;5906.675132867669;5716.485516303511;5744.454276843693;5748.16651585522;5748.942510574882;5750.7412166452195;13443.327833608379;13477.191368517822;13488.79766803489;13563.118397304144;13590.456381744718;2559.4881023795315;2633.9033969269335;2760.1581517637114;2798.3004248937764;2869.856507174633;10038.749031274203;10234.948825255826;10290.279680422373;13606.795898061537;15779.13214773187;1989.2196484146752;2002.869813462135;2011.1352118589;2054.3528788431745;2073.5749171419975;71613.64193179052;81453.59273203656;82316.58841705813;82674.9999999994;87244.56377718071;11293.322400655987;11657.417129143678;12118.939405303094;12143.635690929286;12189.756204875854;17009.40467083671;17011.914940425468;17021.063841488838;17027.148642567823;17057.402990895258;8638.481691098554;8784.516851573097;8955.373123881265;9081.318901082444;9124.726258212264;8046.517208967481;8097.554634495159;8180.100188979208;8249.917500825035;8444.282236009885;4528.932066019012;4574.656900732459;4597.241455159928;4954.826581069645;5196.68819870809;1808.8061879159955;1861.4415675297357;2018.777934427212;2053.835693144545;2169.5986242545177;1012.6481147667946;1020.3469779341227;1074.2909679611419;1077.2405454154803;1085.5549224817787;21393.67909481359;22201.44496387603;22226.555468890532;22265.777342226695;22331.21840735599;1029.9897001029894;1074.3070719385953;1080.195254535376;1088.542812018954;1116.938568378747;11328.376939268419;12200.389980500939;12209.450574724158;12325.815112773316;12343.444544995496;522934;528687;531519;532603;536405;95453;100715;101559.49220253999;101766.49116754369;102771;1071.8820929697727;1082.1288862465633;1115.70991542198;1116.7598966222301;1128.7968165730247;18829.24683012719;19117.426477205707;19296.614067718285;19352.8064719351;19536.60926781485;33814.830925845214;41768.373474397304;46089.53910460987;46441.76779116218;46441.981271217264;62952.37047629465;64708;67688.6615566919;68370;69284;8908.777280567778;9066.138716821892;9155.176034156691;9201.30990175753;9446.244300456085;12136.635900922987;13295.069345145746;13568.59294221175;13736.038477306893;13815.723685526435;11285.322880627189;11369.602063927723;11502.19484636101;11905.642830715093;12265.509379625071;11309.660710178709;11662.067034637039;11712.531498739954;11787.292762434281;11995.520179192734;11890.346030968034;11959.820602691141;12015.81976270374;12052.457639406422;12247.326397048248;8943.463392196489;9034.548272586477;9042.638294468148;9163.404378715362;9304.767380815534;11039.227254092137;11689.941550292195;12011.279323240633;12115.818262726243;12411.379431028232 07:46:13 INFO - PROCESS | 5605 | |3;mootools.html;1399.9580012599627;1560.1263292556168;1596;1800.5858652509921;1925.653382391169;8335.333173346133;9376.484293363863;9725.854112188304;9772.364796288246;10037.899621003788;9528.475933823636;9610;9621.663241786538;9635;9742.951285243582;2544.6819147606548;2563.4488584954233;2571.1001149597587;2572.524083044643;2579.496998085374;2518.64738936549;2588.9741102588923;2594.4292255703713;2597.493488769691;2602.8958841646313;2543.55487789637;2550.1202085280547;2569.6916370035624;2576.716561178269;2577.6777902762155;2422.358075110097;2424.612062070069;2426.9271921842374;2456.803455723538;2458.803295736355;2449.8407603505893;2451.754824517534;2458.6434966929874;2461.027893981885;2495.05188028548;9486.383385080017;9958.302918795687;9997.950010249975;10002.499875006293;10023.69928902134;5671.2060311556415;5790.68151251681;5795.9420405796245;5796.391378905197;5799.884002319972;3717.219383929378;3721.813909304524;3787.2425514896854;3792.260509200707;3801.6388443097862;1161.3148242536943;1162.343276049038;1164.7612239490884;1184.9822252666218;1185.342135115011;14075.929620351935;14093.365798539091;14157.433702651882;14170.149791012469;14178.574642760837;4901.068796928572;4973.204287314013;5004.3994720633755;5062;5151.639385243072;10978.341299522055;10996.450177491253;10997.835032474519;11000.779984400267;11001.889981100247;5782.508486778587;5804.100364443518;5804.448577385142;5813.447722466359;5829.329627092939;4751.501092385319;4781.6891902026255;5287.41838397776;5334.866628334323;5412.702301373462;3996.8001599920467;4127.360259159835;4136.4415803866095;4157.688173387008;4240.809163587646;1177.5289884046279;1185.146694380045;1196.2642974570576;1202.851277030434;1226.258113841131;1034.2863424237403;1036.3781730961514;1039.7660526381503;1045.8692663417073;1053.4995876958537;6183.598066125687;6336.619802811846;6504;6518.641474718841;6582.703778329987;502.50252250271967;513.4044508370273;518.636954132109;521.559282406366;534.7700488789858;4585;4682.274247491644;4753.738544380092;4758.547937945958;4830.227163653869;11880.227785194107;12506.187097838794;12611;12645;12680.112392132647;11133.721656958642;12538.122331436894;12555.372231388394;12573.182743121668;12585.37073146339;8823.382363234641;8977;8999.10008999108;9015.864762028576;9027.593758281024;6658.300878407891;6673.599584024974;6801.251862295045;6891.931080689129;6997.335253150943;10491.160707143563;10649.201309901633;10649.414282214553;10759.731006724895;10775.353478791298;10390.792184156426;11048;11145.55417783312;11228;11309.208355415041;8497.702580409656;8783.472991620523;8814.51520166397;8894.777630559287;8914.55427228649;9644.324897257125;9713.660021899199;9773.267004974514;9781.266405019795;9784;8377.70678026266;8448.957755211184;8497.192766687154;8512;8834.514101724468;8487.321014318717;8528.829423411622;8609.311255099452;8637.8704319434;8812.86780698303;7324.08448943882;7454.962725186339;7562.546247225184;7719.459637825523;7931.722389716563;5731.455511726379;5769.07694768835;5824.592278540462;5845.912311315248;5902.852428689317;5840.8247752567495;5849.5612829037145;5866.7439571038485;5894.646321220741;5985.072313791369 07:46:13 INFO - PROCESS | 5605 | |4;prototype.html;341.1998862667045;345.93254315408495;348.85348153775413;350.90525558099296;358.64315006568467;5498.917516237255;5545.112781954888;5552.444755524445;5555.472230138135;5617.157426386034;5372.2210279509445;5432.375276843164;5503.504684578387;5526.392096869341;5542.725173210164;3872.186840763443;4071.5114186297537;4329.350597410398;4345.391645169679;4350.825966961318;4343.891402714941;4344.370066340379;4345.630621397185;4345.869623911288;4407.457389913537;4115.464989551369;4325.221460137174;4340.891477713051;4369.213541562517;4375.978120109395;4026.9194616107516;4239.342901850203;4259.531451540343;4358.171947329997;4368.257396242647;3829.6553310202216;3843.442700808395;3846.2499812536566;3882.2235552889533;3914;5245.344331958505;5316.3620365556;5317.867053323659;5384.596155288369;5388.353397592275;4868.68545492715;4880.072786170616;4882.829100981448;4923.4091908970795;4945.480724523945;5647.830565083013;5650.830475085754;5664.745086471119;5665.2351932489;5684.175794509773;3886.9222615547815;4073.6333729964445;4185.267578173838;4205.495340559122;4229.942514371407;5640.774369025234;5821.18503409523;5936.833055934596;5966.701664916737;5980.910286345665;4136.482939632546;4154.127633197062;4157.521884983205;4191.350340697197;4203.07532342884;4267.893302667459;4268.701190916669;4271.615554600101;4272.743635381887;4273.957260427418;4624.907501849944;4872.561469467765;4896.2410826321975;4905.337779399735;4906.288588154697;5742.913856292159;5743.368229494752;5761.366249712528;5765.279340082489;5838.562107841929;1460.4450308882556;1476.4463326252655;1477.9778303325459;1481.1187343530582;1491.4854375240525;915.8385919102587;932.6129656192604;933.7208025005785;940.7648087978006;950.5532399772096;4952.702837829742;4965.751712414437;4992.377477319886;5021.246812978009;5070.112730272262;5194.4805519447755;5197.142471492161;5209.7916083356995;5224.555912747383;5233.947660523422;4734.928976065362;4987.326710893983;4998.750062496861;5011.3986321640905;5093.326003847936;2786.4984302825687;2802.691703912568;2806.7052959439375;2807.3122085088876;2815.1982731052144;5402.7583449929725;5408.45915408456;5429.041172560964;5442.265294185314;5468.425815289337;5137.845864624067;5247.868803279948;5285.2600635911;5295.735213239323;5344.690550815075;5347.732613369316;5372.946270537323;5379.892402151857;5387.3265841769;5407.810726624549;5201.557867581147;5229.816956406507;5308.920366194588;5320.446185830746;5356.223347614652;4865.756712164449;5088.7940597423385;5105.2342148678;5187.04039752647;5203.895922081613;5314.272861320163;5793.333766616893;5926.4666180044005;6086.665233412028;6095.299040610386;4230.7884605770205;4242.830286788494;4266.466691663542;4277.700560960703;4296.775419005568;5736.741846616996;5951.434613711691;5999.4900433463645;6003.519718422603;6004.939950600438;5742.966266072147;5746.367899531132;5748.252727145445;5767.279090113736;5772.6536407815665;5716.228309178374;5741.741621626953;5754.136879468113;5761.214023652681;5802.448767367094;5464.863378415572;5676.46735381453;5681.4034526374135;5693.202951586864;5698.230738850368;5733.799317023884;5737.91393129095;5742.885142297214;5746.195532625352;5774.314943551269;5643.407442218508;5656.490915817596;5697.350731909516;5699.857503562444;5758.280214973129 07:46:13 INFO - PROCESS | 5605 | |5;yui.html;856.9014563325219;870.0820634230884;892.2371372476529;935.9485228312449;953.837847565914;16060.27728752206;17195.74206386902;17224;17226.913865430655;17227.138643067858;16456.83543164568;16600.169991500436;16612.750808737877;16613.41853035144;16627.251773670185;733.6808488307603;861.9569021548898;891.4473026723441;894.2622336572354;901.5852707754408;912.3431129586687;914.437620863171;914.6112902016649;915.7069737683944;916.0290092501936;959.4195511715382;960.5005397193455;960.7117864640614;960.9471479068649;961.1781926452852;783.34199272611;915.1260546178416;938.1369140390857;949.9620015199383;950.396498223626;700.4676445901101;701.6000879498637;701.828052127227;702.6381413572014;702.9894551581731;15565.45520906774;15607;15614.375424982987;15626.843731562767;15627.374905003786;14658.486952956595;14661.633459163606;14853.108813471226;14861.777073343801;15006;13593.660158496117;13934.93032534841;14213.360398782079;14269.00116991811;14287.928560357235;4730.62155027597;4749.691270067435;4766.142094423002;4843.24929635907;4851.442084160331;83820.99999999939;84689.57655211685;85390;86497.56751216203;86587;11779.52881884715;12088;12192;12371.938140309241;12537.811932821014;23978.16076437351;24123.155689550782;24130;24130.7586924132;24136.51726965451;9039.502827344559;9048;9160;9284.118008789153;9452.95273523628;4843.782029808667;4895.657303988758;4914.533119353656;4983.825566105169;5160.458151894072;3749.850005999784;3877.185790983925;3879.86420475282;3881.3983832506005;3887.844486220576;677.2347247610169;685.348918527391;713.7466199499207;721.4012369733109;729.4310437858479;706.1067749297143;725.2747252747253;750.4634186556639;798.217746608327;856.3277826905849;10174.4912754362;10404.271700980858;10713.303635263683;10756.031957123896;10950.945245273722;749.5914726474085;766.6473422225727;793.6745934166964;808.7048227397075;822.457178262344;5259.132243179833;5353.705546194997;5428.511433970962;5435.076037073707;5437.592180586472;126837;132673.33663331813;133722;134186.32906835404;134390.3280483611;113310.43344783389;114018;117452;118485.4075729633;118886;91192.54403727938;97697;98650;99733.50133249287;100014.99999999854;10083.294169408071;10302.536385862804;10312.896871031493;10372.688819335432;10428.322159059637;56115.71942140263;58133.63997700274;58146.7092664534;59450;60222.698886505284;80700.59649701713;85826.57086714526;86848;87498.9447967299;88457.55771221103;12959.870401296244;13029.218246905217;13163.276019819003;13242.668933276746;13382.330883455594;14441.422343106158;14560.19918904428;15129.39482420691;15268.160251186291;15609.141497217763;13397.53108641193;13622.591322260349;13701;13906.443742250602;14186.787198192242;12960.740785184433;13005;13249;13404.597862064154;13858;13478.932605336911;13522.25627590492;13697;13847.861521384657;14608.196549189897;10589.788204236027;10985.340879546933;11098.5560577576;11222.102231821598;11359.943200283946;11416.257943233662;11553.537858485566;11589.536418543164;11716.94141529287;11757.353345566076 07:46:13 INFO - PROCESS | 5605 | __end_tp_report 07:46:13 INFO - PROCESS | 5605 | __start_cc_report 07:46:13 INFO - PROCESS | 5605 | _x_x_mozilla_cycle_collect,1049 07:46:13 INFO - PROCESS | 5605 | __end_cc_report 07:46:13 INFO - PROCESS | 5605 | __startTimestamp1474123573370__endTimestamp 07:46:13 INFO - PROCESS | 5605 | 07:46:13 INFO - PROCESS | 5605 | ------- Summary: start ------- 07:46:13 INFO - PROCESS | 5605 | Number of tests: 6 07:46:13 INFO - PROCESS | 5605 | 07:46:13 INFO - PROCESS | 5605 | [#0] dojo.html Cycles:165 Average:5676.42 Median:5927.82 stddev:656.79 (11.1%) stddev-sans-first:629.68 07:46:13 INFO - PROCESS | 5605 | Values: 3210.9 3220.8 3245.9 3280.0 3300.4 5595.1 5651.8 5684.9 5690.6 5695.2 5698.4 5757.7 5762.7 5768.5 5775.0 5071.9 5144.0 5244.4 5247.3 5296.1 5245.2 5297.2 5300.8 5305.0 5307.1 5148.4 5167.7 5186.5 5194.5 5289.0 5322.1 5335.5 5336.1 5341.9 5359.0 5020.5 5102.7 5122.0 5222.5 5223.0 5581.1 5585.3 5603.6 5621.6 5631.0 6098.5 6098.6 6101.3 6106.8 6108.7 6081.2 6085.0 6135.0 6143.9 6194.6 5712.3 5766.3 5782.0 5787.7 5792.2 6057.9 6330.9 6336.1 6343.6 6344.2 5897.4 5945.1 5945.8 6025.4 6034.5 6074.8 6076.5 6079.1 6099.0 6105.6 5904.0 6043.0 6050.7 6054.4 6054.6 6056.7 6100.9 6104.6 6130.4 6164.0 4654.3 4690.6 4699.1 4720.9 4731.1 4133.0 4176.7 4201.4 4205.7 4223.4 5723.9 5724.0 5794.7 5879.1 5890.3 6125.9 6142.0 6145.5 6147.1 6151.9 5824.8 5845.6 5848.7 5851.2 5853.5 4823.5 4886.9 4901.7 4915.3 5001.6 6325.7 6353.1 6357.0 6357.1 6379.0 6127.6 6147.2 6157.6 6170.5 6182.6 6169.5 6208.8 6226.8 6271.7 6351.3 6037.7 6043.6 6044.5 6051.8 6087.0 6162.1 6163.2 6170.2 6170.4 6174.4 5903.7 5932.7 5946.7 5971.0 6068.6 5960.7 6010.3 6011.3 6015.9 6024.9 5917.6 5947.2 5954.9 5993.5 5995.3 5927.8 5963.5 6005.3 6009.1 6098.2 5906.5 5918.6 5918.7 5928.4 5932.0 07:46:13 INFO - PROCESS | 5605 | 07:46:13 INFO - PROCESS | 5605 | [#1] ext.html Cycles:180 Average:64422.61 Median:410703.00 stddev:127776.41 (31.1%) stddev-sans-first:128044.99 07:46:13 INFO - PROCESS | 5605 | Values: 594.5 613.7 634.7 653.2 659.3 34231.1 37247.4 37361.8 46935.8 47794.0 47734.3 48033.0 48074.8 48127.8 48157.0 9273.8 9419.6 9419.8 9430.6 9432.1 8644.4 10954.4 11219.3 11223.7 11570.4 11452.1 11556.9 11558.5 11570.2 11578.5 11328.0 11362.4 11397.7 11407.1 11414.1 11272.0 11348.2 11354.4 11362.0 11367.5 45381.1 45554.4 45590.3 45594.0 45635.8 4244.7 4527.7 4634.7 4648.8 4724.5 35452.5 35520.3 35948.0 36873.8 37195.6 4459.4 4539.8 4556.9 4567.9 4682.4 377951.0 379112.0 379657.0 380429.0 381280.0 38537.6 64481.4 64639.4 64796.0 65010.7 38712.4 40270.6 40335.2 40546.8 40602.4 14308.2 14383.9 14391.1 14392.9 14434.6 13680.1 14938.9 15019.2 15133.8 15156.1 9043.1 9165.8 9217.7 9235.2 9317.1 3446.3 3511.6 3513.3 3553.9 3594.1 1106.4 1137.6 1163.3 1224.1 1244.2 33167.3 35189.2 35294.0 35433.3 35751.0 1171.6 1187.7 1213.7 1257.4 1272.6 18319.6 18518.7 18643.4 18717.8 18792.3 613800.0 614966.0 619521.0 620083.0 620736.0 64402.0 64677.4 64796.7 64851.0 65069.3 1352.2 1412.8 1460.9 1490.8 1496.9 34658.5 37241.4 37364.4 37551.0 37580.0 54326.5 54726.7 54772.2 54843.7 54851.2 407910.0 410299.0 411107.0 412866.0 413770.0 51173.5 51493.2 51654.7 51778.7 52396.2 54199.5 54549.0 54663.5 55123.0 55228.7 45034.0 47230.0 47610.3 47847.8 48304.5 44783.3 46276.3 46539.8 46855.8 48220.0 46650.5 46791.5 47106.5 48048.5 48089.8 38261.4 41931.0 42185.6 42243.0 42511.8 31103.2 31340.5 31723.7 32188.7 32242.0 07:46:13 INFO - PROCESS | 5605 | 07:46:13 INFO - PROCESS | 5605 | [#2] jquery.html Cycles:180 Average:30835.46 Median:21411.68 stddev:87484.88 (408.6%) stddev-sans-first:87700.43 07:46:13 INFO - PROCESS | 5605 | Values: 391.9 426.9 428.9 439.6 442.7 16819.9 21009.5 21429.7 21450.9 21513.9 19650.5 20037.2 20066.8 20098.8 20297.4 5585.2 5879.7 5916.1 5923.3 5960.7 5751.3 5898.1 5928.4 5931.4 5932.3 5892.0 5921.5 5923.3 5927.1 5934.3 5724.4 5809.8 5898.4 5904.3 5906.7 5716.5 5744.5 5748.2 5748.9 5750.7 13443.3 13477.2 13488.8 13563.1 13590.5 2559.5 2633.9 2760.2 2798.3 2869.9 10038.7 10234.9 10290.3 13606.8 15779.1 1989.2 2002.9 2011.1 2054.4 2073.6 71613.6 81453.6 82316.6 82675.0 87244.6 11293.3 11657.4 12118.9 12143.6 12189.8 17009.4 17011.9 17021.1 17027.1 17057.4 8638.5 8784.5 8955.4 9081.3 9124.7 8046.5 8097.6 8180.1 8249.9 8444.3 4528.9 4574.7 4597.2 4954.8 5196.7 1808.8 1861.4 2018.8 2053.8 2169.6 1012.6 1020.3 1074.3 1077.2 1085.6 21393.7 22201.4 22226.6 22265.8 22331.2 1030.0 1074.3 1080.2 1088.5 1116.9 11328.4 12200.4 12209.5 12325.8 12343.4 522934.0 528687.0 531519.0 532603.0 536405.0 95453.0 100715.0 101559.5 101766.5 102771.0 1071.9 1082.1 1115.7 1116.8 1128.8 18829.2 19117.4 19296.6 19352.8 19536.6 33814.8 41768.4 46089.5 46441.8 46442.0 62952.4 64708.0 67688.7 68370.0 69284.0 8908.8 9066.1 9155.2 9201.3 9446.2 12136.6 13295.1 13568.6 13736.0 13815.7 11285.3 11369.6 11502.2 11905.6 12265.5 11309.7 11662.1 11712.5 11787.3 11995.5 11890.3 11959.8 12015.8 12052.5 12247.3 8943.5 9034.5 9042.6 9163.4 9304.8 11039.2 11689.9 12011.3 12115.8 12411.4 07:46:13 INFO - PROCESS | 5605 | 07:46:13 INFO - PROCESS | 5605 | [#3] mootools.html Cycles:180 Average:6395.15 Median:4752.62 stddev:3717.36 (78.2%) stddev-sans-first:3708.83 07:46:13 INFO - PROCESS | 5605 | Values: 1400.0 1560.1 1596.0 1800.6 1925.7 8335.3 9376.5 9725.9 9772.4 10037.9 9528.5 9610.0 9621.7 9635.0 9743.0 2544.7 2563.4 2571.1 2572.5 2579.5 2518.6 2589.0 2594.4 2597.5 2602.9 2543.6 2550.1 2569.7 2576.7 2577.7 2422.4 2424.6 2426.9 2456.8 2458.8 2449.8 2451.8 2458.6 2461.0 2495.1 9486.4 9958.3 9998.0 10002.5 10023.7 5671.2 5790.7 5795.9 5796.4 5799.9 3717.2 3721.8 3787.2 3792.3 3801.6 1161.3 1162.3 1164.8 1185.0 1185.3 14075.9 14093.4 14157.4 14170.1 14178.6 4901.1 4973.2 5004.4 5062.0 5151.6 10978.3 10996.5 10997.8 11000.8 11001.9 5782.5 5804.1 5804.4 5813.4 5829.3 4751.5 4781.7 5287.4 5334.9 5412.7 3996.8 4127.4 4136.4 4157.7 4240.8 1177.5 1185.1 1196.3 1202.9 1226.3 1034.3 1036.4 1039.8 1045.9 1053.5 6183.6 6336.6 6504.0 6518.6 6582.7 502.5 513.4 518.6 521.6 534.8 4585.0 4682.3 4753.7 4758.5 4830.2 11880.2 12506.2 12611.0 12645.0 12680.1 11133.7 12538.1 12555.4 12573.2 12585.4 8823.4 8977.0 8999.1 9015.9 9027.6 6658.3 6673.6 6801.3 6891.9 6997.3 10491.2 10649.2 10649.4 10759.7 10775.4 10390.8 11048.0 11145.6 11228.0 11309.2 8497.7 8783.5 8814.5 8894.8 8914.6 9644.3 9713.7 9773.3 9781.3 9784.0 8377.7 8449.0 8497.2 8512.0 8834.5 8487.3 8528.8 8609.3 8637.9 8812.9 7324.1 7455.0 7562.5 7719.5 7931.7 5731.5 5769.1 5824.6 5845.9 5902.9 5840.8 5849.6 5866.7 5894.6 5985.1 07:46:13 INFO - PROCESS | 5605 | 07:46:13 INFO - PROCESS | 5605 | [#4] prototype.html Cycles:180 Average:4687.60 Median:5227.19 stddev:1350.22 (25.8%) stddev-sans-first:1314.00 07:46:13 INFO - PROCESS | 5605 | Values: 341.2 345.9 348.9 350.9 358.6 5498.9 5545.1 5552.4 5555.5 5617.2 5372.2 5432.4 5503.5 5526.4 5542.7 3872.2 4071.5 4329.4 4345.4 4350.8 4343.9 4344.4 4345.6 4345.9 4407.5 4115.5 4325.2 4340.9 4369.2 4376.0 4026.9 4239.3 4259.5 4358.2 4368.3 3829.7 3843.4 3846.2 3882.2 3914.0 5245.3 5316.4 5317.9 5384.6 5388.4 4868.7 4880.1 4882.8 4923.4 4945.5 5647.8 5650.8 5664.7 5665.2 5684.2 3886.9 4073.6 4185.3 4205.5 4229.9 5640.8 5821.2 5936.8 5966.7 5980.9 4136.5 4154.1 4157.5 4191.4 4203.1 4267.9 4268.7 4271.6 4272.7 4274.0 4624.9 4872.6 4896.2 4905.3 4906.3 5742.9 5743.4 5761.4 5765.3 5838.6 1460.4 1476.4 1478.0 1481.1 1491.5 915.8 932.6 933.7 940.8 950.6 4952.7 4965.8 4992.4 5021.2 5070.1 5194.5 5197.1 5209.8 5224.6 5233.9 4734.9 4987.3 4998.8 5011.4 5093.3 2786.5 2802.7 2806.7 2807.3 2815.2 5402.8 5408.5 5429.0 5442.3 5468.4 5137.8 5247.9 5285.3 5295.7 5344.7 5347.7 5372.9 5379.9 5387.3 5407.8 5201.6 5229.8 5308.9 5320.4 5356.2 4865.8 5088.8 5105.2 5187.0 5203.9 5314.3 5793.3 5926.5 6086.7 6095.3 4230.8 4242.8 4266.5 4277.7 4296.8 5736.7 5951.4 5999.5 6003.5 6004.9 5743.0 5746.4 5748.3 5767.3 5772.7 5716.2 5741.7 5754.1 5761.2 5802.4 5464.9 5676.5 5681.4 5693.2 5698.2 5733.8 5737.9 5742.9 5746.2 5774.3 5643.4 5656.5 5697.4 5699.9 5758.3 07:46:13 INFO - PROCESS | 5605 | 07:46:13 INFO - PROCESS | 5605 | [#5] yui.html Cycles:180 Average:23293.23 Median:13943.18 stddev:34544.55 (247.8%) stddev-sans-first:34600.38 07:46:13 INFO - PROCESS | 5605 | Values: 856.9 870.1 892.2 935.9 953.8 16060.3 17195.7 17224.0 17226.9 17227.1 16456.8 16600.2 16612.8 16613.4 16627.3 733.7 862.0 891.4 894.3 901.6 912.3 914.4 914.6 915.7 916.0 959.4 960.5 960.7 960.9 961.2 783.3 915.1 938.1 950.0 950.4 700.5 701.6 701.8 702.6 703.0 15565.5 15607.0 15614.4 15626.8 15627.4 14658.5 14661.6 14853.1 14861.8 15006.0 13593.7 13934.9 14213.4 14269.0 14287.9 4730.6 4749.7 4766.1 4843.2 4851.4 83821.0 84689.6 85390.0 86497.6 86587.0 11779.5 12088.0 12192.0 12371.9 12537.8 23978.2 24123.2 24130.0 24130.8 24136.5 9039.5 9048.0 9160.0 9284.1 9453.0 4843.8 4895.7 4914.5 4983.8 5160.5 3749.9 3877.2 3879.9 3881.4 3887.8 677.2 685.3 713.7 721.4 729.4 706.1 725.3 750.5 798.2 856.3 10174.5 10404.3 10713.3 10756.0 10950.9 749.6 766.6 793.7 808.7 822.5 5259.1 5353.7 5428.5 5435.1 5437.6 126837.0 132673.3 133722.0 134186.3 134390.3 113310.4 114018.0 117452.0 118485.4 118886.0 91192.5 97697.0 98650.0 99733.5 100015.0 10083.3 10302.5 10312.9 10372.7 10428.3 56115.7 58133.6 58146.7 59450.0 60222.7 80700.6 85826.6 86848.0 87498.9 88457.6 12959.9 13029.2 13163.3 13242.7 13382.3 14441.4 14560.2 15129.4 15268.2 15609.1 13397.5 13622.6 13701.0 13906.4 14186.8 12960.7 13005.0 13249.0 13404.6 13858.0 13478.9 13522.3 13697.0 13847.9 14608.2 10589.8 10985.3 11098.6 11222.1 11359.9 11416.3 11553.5 11589.5 11716.9 11757.4 07:46:13 INFO - PROCESS | 5605 | -------- Summary: end -------- 07:46:13 INFO - PROCESS | 5605 | 07:46:14 INFO - TEST-INFO | 5605: exit 0 07:46:14 INFO - TEST-OK | dromaeo_css | took 1113075ms 07:46:14 INFO - TEST-START | kraken 07:46:14 INFO - operating with platform_type : linux_ 07:46:14 INFO - Initialising browser for kraken test... 07:46:14 INFO - TEST-INFO | started process 5806 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRiadIV/profile http://localhost:38011/getInfo.html) 07:46:18 INFO - PROCESS | 5806 | __metrics Screen width/height:1600/1200 07:46:18 INFO - PROCESS | 5806 | colorDepth:24 07:46:18 INFO - PROCESS | 5806 | Browser inner width/height: 1024/697 07:46:18 INFO - PROCESS | 5806 | __metrics 07:46:18 INFO - PROCESS | 5806 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:46:18 INFO - PROCESS | 5806 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:46:19 INFO - TEST-INFO | 5806: exit 0 07:46:19 INFO - Browser initialized. 07:46:19 INFO - Running cycle 1/1 for kraken test... 07:46:19 INFO - TEST-INFO | started process 5891 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRiadIV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:46:31 INFO - PROCESS | 5891 | RSS: Main: 146247680 07:46:31 INFO - PROCESS | 5891 | 07:46:34 INFO - PROCESS | 5891 | Cycle 1(1): loaded http://localhost:38011/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38011/tests/kraken/driver.html?testName=audio-beat-detection) 07:46:34 INFO - PROCESS | 5891 | RSS: Main: 201027584 07:46:34 INFO - PROCESS | 5891 | 07:46:37 INFO - PROCESS | 5891 | Cycle 1(1): loaded http://localhost:38011/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38011/tests/kraken/driver.html?testName=audio-dft) 07:46:38 INFO - PROCESS | 5891 | RSS: Main: 184934400 07:46:38 INFO - PROCESS | 5891 | 07:46:40 INFO - PROCESS | 5891 | Cycle 1(1): loaded http://localhost:38011/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38011/tests/kraken/driver.html?testName=audio-fft) 07:46:41 INFO - PROCESS | 5891 | RSS: Main: 276086784 07:46:41 INFO - PROCESS | 5891 | 07:46:43 INFO - PROCESS | 5891 | Cycle 1(1): loaded http://localhost:38011/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38011/tests/kraken/driver.html?testName=audio-oscillator) 07:46:44 INFO - PROCESS | 5891 | RSS: Main: 181587968 07:46:44 INFO - PROCESS | 5891 | 07:46:46 INFO - PROCESS | 5891 | Cycle 1(1): loaded http://localhost:38011/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38011/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:46:46 INFO - PROCESS | 5891 | RSS: Main: 181133312 07:46:46 INFO - PROCESS | 5891 | 07:46:49 INFO - PROCESS | 5891 | Cycle 1(1): loaded http://localhost:38011/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38011/tests/kraken/driver.html?testName=imaging-darkroom) 07:46:50 INFO - PROCESS | 5891 | RSS: Main: 216707072 07:46:50 INFO - PROCESS | 5891 | 07:46:53 INFO - PROCESS | 5891 | Cycle 1(1): loaded http://localhost:38011/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38011/tests/kraken/driver.html?testName=imaging-desaturate) 07:46:54 INFO - PROCESS | 5891 | RSS: Main: 214896640 07:46:54 INFO - PROCESS | 5891 | 07:46:57 INFO - PROCESS | 5891 | Cycle 1(1): loaded http://localhost:38011/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38011/tests/kraken/driver.html?testName=json-parse-financial) 07:46:57 INFO - PROCESS | 5891 | RSS: Main: 215142400 07:46:57 INFO - PROCESS | 5891 | 07:46:59 INFO - PROCESS | 5891 | Cycle 1(1): loaded http://localhost:38011/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38011/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:46:59 INFO - PROCESS | 5891 | RSS: Main: 177664000 07:46:59 INFO - PROCESS | 5891 | 07:47:01 INFO - PROCESS | 5891 | Cycle 1(1): loaded http://localhost:38011/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38011/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:47:02 INFO - PROCESS | 5891 | RSS: Main: 184139776 07:47:02 INFO - PROCESS | 5891 | 07:47:04 INFO - PROCESS | 5891 | Cycle 1(1): loaded http://localhost:38011/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38011/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:47:04 INFO - PROCESS | 5891 | RSS: Main: 186707968 07:47:04 INFO - PROCESS | 5891 | 07:47:07 INFO - PROCESS | 5891 | Cycle 1(1): loaded http://localhost:38011/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38011/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:47:07 INFO - PROCESS | 5891 | RSS: Main: 181682176 07:47:07 INFO - PROCESS | 5891 | 07:47:10 INFO - PROCESS | 5891 | Cycle 1(1): loaded http://localhost:38011/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38011/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:47:11 INFO - PROCESS | 5891 | RSS: Main: 184201216 07:47:11 INFO - PROCESS | 5891 | 07:47:12 INFO - PROCESS | 5891 | Cycle 1(1): loaded http://localhost:38011/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38011/tests/kraken/driver.html?testName=ai-astar) 07:47:13 INFO - PROCESS | 5891 | RSS: Main: 236404736 07:47:13 INFO - PROCESS | 5891 | 07:47:13 INFO - PROCESS | 5891 | __start_tp_report 07:47:13 INFO - PROCESS | 5891 | _x_x_mozilla_page_load 07:47:13 INFO - PROCESS | 5891 | _x_x_mozilla_page_load_details 07:47:13 INFO - PROCESS | 5891 | |i|pagename|runs| 07:47:13 INFO - PROCESS | 5891 | |0;ai-astar;110;111;118;109;109;109;109;109;109;110 07:47:13 INFO - PROCESS | 5891 | |1;audio-beat-detection;149;114;145;163;116;136;147;119;174;117 07:47:13 INFO - PROCESS | 5891 | |2;audio-dft;159;160;161;161;162;161;158;161;162;159 07:47:13 INFO - PROCESS | 5891 | |3;audio-fft;74;74;75;188;74;73;73;73;162;74 07:47:13 INFO - PROCESS | 5891 | |4;audio-oscillator;82;83;162;84;84;83;145;83;83;145 07:47:13 INFO - PROCESS | 5891 | |5;imaging-gaussian-blur;117;116;117;116;117;117;117;117;116;117 07:47:13 INFO - PROCESS | 5891 | |6;imaging-darkroom;146;147;146;148;148;147;146;146;148;147 07:47:13 INFO - PROCESS | 5891 | |7;imaging-desaturate;95;96;95;95;94;94;95;95;95;94 07:47:13 INFO - PROCESS | 5891 | |8;json-parse-financial;51;50;49;50;50;50;50;49;50;52 07:47:13 INFO - PROCESS | 5891 | |9;json-stringify-tinderbox;61;63;60;61;60;60;61;62;61;61 07:47:13 INFO - PROCESS | 5891 | |10;stanford-crypto-aes;72;72;72;72;72;73;73;81;74;74 07:47:13 INFO - PROCESS | 5891 | |11;stanford-crypto-ccm;119;117;117;124;117;115;117;115;144;122 07:47:13 INFO - PROCESS | 5891 | |12;stanford-crypto-pbkdf2;153;152;152;156;153;153;155;153;150;155 07:47:13 INFO - PROCESS | 5891 | |13;stanford-crypto-sha256-iterative;62;62;62;63;61;61;64;64;61;60 07:47:13 INFO - PROCESS | 5891 | __end_tp_report 07:47:13 INFO - PROCESS | 5891 | __start_cc_report 07:47:13 INFO - PROCESS | 5891 | _x_x_mozilla_cycle_collect,1682 07:47:13 INFO - PROCESS | 5891 | __end_cc_report 07:47:13 INFO - PROCESS | 5891 | __startTimestamp1474123633040__endTimestamp 07:47:13 INFO - PROCESS | 5891 | 07:47:13 INFO - PROCESS | 5891 | ------- Summary: start ------- 07:47:13 INFO - PROCESS | 5891 | Number of tests: 14 07:47:13 INFO - PROCESS | 5891 | 07:47:13 INFO - PROCESS | 5891 | [#0] ai-astar Cycles:10 Average:110.30 Median:109.50 stddev:2.79 (2.5%) stddev-sans-first:2.96 07:47:13 INFO - PROCESS | 5891 | Values: 110.0 111.0 118.0 109.0 109.0 109.0 109.0 109.0 109.0 110.0 07:47:13 INFO - PROCESS | 5891 | 07:47:13 INFO - PROCESS | 5891 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:146.00 stddev:21.18 (14.5%) stddev-sans-first:22.09 07:47:13 INFO - PROCESS | 5891 | Values: 149.0 114.0 145.0 163.0 116.0 136.0 147.0 119.0 174.0 117.0 07:47:13 INFO - PROCESS | 5891 | 07:47:13 INFO - PROCESS | 5891 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:1.35 (0.8%) stddev-sans-first:1.33 07:47:13 INFO - PROCESS | 5891 | Values: 159.0 160.0 161.0 161.0 162.0 161.0 158.0 161.0 162.0 159.0 07:47:13 INFO - PROCESS | 5891 | 07:47:13 INFO - PROCESS | 5891 | [#3] audio-fft Cycles:10 Average:94.00 Median:74.00 stddev:43.13 (58.3%) stddev-sans-first:45.14 07:47:13 INFO - PROCESS | 5891 | Values: 74.0 74.0 75.0 188.0 74.0 73.0 73.0 73.0 162.0 74.0 07:47:13 INFO - PROCESS | 5891 | 07:47:13 INFO - PROCESS | 5891 | [#4] audio-oscillator Cycles:10 Average:103.40 Median:83.00 stddev:32.95 (39.7%) stddev-sans-first:34.02 07:47:13 INFO - PROCESS | 5891 | Values: 82.0 83.0 162.0 84.0 84.0 83.0 145.0 83.0 83.0 145.0 07:47:13 INFO - PROCESS | 5891 | 07:47:13 INFO - PROCESS | 5891 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 07:47:13 INFO - PROCESS | 5891 | Values: 117.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 07:47:13 INFO - PROCESS | 5891 | 07:47:13 INFO - PROCESS | 5891 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.88 (0.6%) stddev-sans-first:0.87 07:47:13 INFO - PROCESS | 5891 | Values: 146.0 147.0 146.0 148.0 148.0 147.0 146.0 146.0 148.0 147.0 07:47:13 INFO - PROCESS | 5891 | 07:47:13 INFO - PROCESS | 5891 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 07:47:13 INFO - PROCESS | 5891 | Values: 95.0 96.0 95.0 95.0 94.0 94.0 95.0 95.0 95.0 94.0 07:47:13 INFO - PROCESS | 5891 | 07:47:13 INFO - PROCESS | 5891 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:0.88 (1.8%) stddev-sans-first:0.87 07:47:13 INFO - PROCESS | 5891 | Values: 51.0 50.0 49.0 50.0 50.0 50.0 50.0 49.0 50.0 52.0 07:47:13 INFO - PROCESS | 5891 | 07:47:13 INFO - PROCESS | 5891 | [#9] json-stringify-tinderbox Cycles:10 Average:61.00 Median:61.00 stddev:0.94 (1.5%) stddev-sans-first:1.00 07:47:13 INFO - PROCESS | 5891 | Values: 61.0 63.0 60.0 61.0 60.0 60.0 61.0 62.0 61.0 61.0 07:47:13 INFO - PROCESS | 5891 | 07:47:13 INFO - PROCESS | 5891 | [#10] stanford-crypto-aes Cycles:10 Average:73.50 Median:73.00 stddev:2.76 (3.8%) stddev-sans-first:2.87 07:47:13 INFO - PROCESS | 5891 | Values: 72.0 72.0 72.0 72.0 72.0 73.0 73.0 81.0 74.0 74.0 07:47:13 INFO - PROCESS | 5891 | 07:47:13 INFO - PROCESS | 5891 | [#11] stanford-crypto-ccm Cycles:10 Average:120.70 Median:118.00 stddev:8.68 (7.4%) stddev-sans-first:9.18 07:47:13 INFO - PROCESS | 5891 | Values: 119.0 117.0 117.0 124.0 117.0 115.0 117.0 115.0 144.0 122.0 07:47:13 INFO - PROCESS | 5891 | 07:47:13 INFO - PROCESS | 5891 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.20 Median:153.00 stddev:1.75 (1.1%) stddev-sans-first:1.86 07:47:13 INFO - PROCESS | 5891 | Values: 153.0 152.0 152.0 156.0 153.0 153.0 155.0 153.0 150.0 155.0 07:47:13 INFO - PROCESS | 5891 | 07:47:13 INFO - PROCESS | 5891 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.00 Median:62.00 stddev:1.33 (2.2%) stddev-sans-first:1.41 07:47:13 INFO - PROCESS | 5891 | Values: 62.0 62.0 62.0 63.0 61.0 61.0 64.0 64.0 61.0 60.0 07:47:13 INFO - PROCESS | 5891 | -------- Summary: end -------- 07:47:13 INFO - PROCESS | 5891 | 07:47:13 INFO - TEST-INFO | 5891: exit 0 07:47:14 INFO - TEST-OK | kraken | took 59901ms 07:47:14 INFO - SUITE-END | took 1172s 07:47:14 INFO - Completed test suite (00:19:33) 07:47:14 INFO - javascript benchmark 07:47:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3210.887618933337, 3220.8389580520966, 3245.902622921312, 3280.048785852103, 3300.356430496053, 5595.132754423066, 5651.830445086649, 5684.914726279109, 5690.573207009481, 5695.202671625976, 5698.373178950323, 5757.654540727559, 5762.711864406773, 5768.538516918647, 5774.971125144369, 5071.873203169914, 5143.974280128594, 5244.370675518933, 5247.291615631898, 5296.09966305729, 5245.160774276116, 5297.205419187114, 5300.787968481256, 5304.973475132619, 5307.071262529062, 5148.407933087687, 5167.715775632339, 5186.455422180673, 5194.480551944813, 5289.021531016755, 5322.121849894763, 5335.493128152858, 5336.092864213093, 5341.893162136736, 5359.0, 5020.49795020495, 5102.744862756885, 5122.0, 5222.503862133091, 5222.973885130588, 5581.05122129239, 5585.273914391144, 5603.579731520153, 5621.578381621394, 5631.0, 6098.481629061535, 6098.634081955097, 6101.2983506896835, 6106.786262480836, 6108.664023478706, 6081.209442772455, 6084.952386641381, 6134.96932515339, 6143.87712245757, 6194.566380353377, 5712.285964254468, 5766.250387449689, 5781.971090144522, 5787.681677507693, 5792.247007888947, 6057.894501364603, 6330.936690633035, 6336.112944187817, 6343.619382837044, 6344.238691357066, 5897.439743224387, 5945.078512830518, 5945.762169513171, 6025.397460253939, 6034.517238620988, 6074.848128796815, 6076.548599957207, 6079.05774604937, 6099.024156134997, 6105.633661980295, 5903.970480147572, 6042.969785151046, 6050.697465126814, 6054.39456054391, 6054.606450580698, 6056.697165141725, 6100.908486372708, 6104.572679912363, 6130.367562715059, 6163.96918015407, 4654.25531914892, 4690.624750020059, 4699.130660827689, 4720.929186062212, 4731.077439899255, 4133.008078061303, 4176.665866730655, 4201.432806571135, 4205.705600607989, 4223.408722778813, 5723.8569035774435, 5724.026915424388, 5794.652320860761, 5879.088741245114, 5890.322612899571, 6125.908111378333, 6142.04798256271, 6145.477634401037, 6147.0779383092, 6151.876962460726, 5824.825255242349, 5845.590808643354, 5848.736806843617, 5851.210086638334, 5853.502452291603, 4823.493533178966, 4886.877828054327, 4901.705897646153, 4915.311856340115, 5001.64988450805, 6325.746970121236, 6353.110564520971, 6356.968215158987, 6357.11000459936, 6379.011253255753, 6127.571070025056, 6147.162045276119, 6157.568970172045, 6170.5372097092, 6182.598131121463, 6169.50643948474, 6208.751649934133, 6226.750929962751, 6271.749130034747, 6351.333110023381, 6037.667928263988, 6043.576949613661, 6044.546659000504, 6051.788187413419, 6086.969565152146, 6162.106494558174, 6163.229596300463, 6170.197874276315, 6170.352113028247, 6174.413430724074, 5903.675297858659, 5932.7033648318275, 5946.73239704223, 5970.9701451492465, 6068.605540639845, 5960.672163030901, 6010.33886272501, 6011.338752737282, 6015.939840601538, 6024.939750602438, 5917.585768996301, 5947.167396564571, 5954.910676339945, 5993.460588547052, 5995.340512543704, 5927.8221653350465, 5963.493103086286, 6005.279366476051, 6009.06859436788, 6098.237720284965, 5906.497947674496, 5918.556108291809, 5918.674472904032, 5928.436798504135, 5932.0], "value": 5630.8925065777175, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.4531031451064, 613.6870196199937, 634.7080343042203, 653.1769969838006, 659.3044338223178, 34231.144221394476, 37247.441288380665, 37361.81319093401, 46935.76532117335, 47794.0441191176, 47734.283985740156, 48033.0, 48074.75962620191, 48127.75936120314, 48157.0, 9273.814523709538, 9419.62321507139, 9419.811603767921, 9430.622775088988, 9432.103950124727, 8644.438111522762, 10954.39750813705, 11219.326840389562, 11223.663290101269, 11570.421478926062, 11452.083833293316, 11556.884431155708, 11558.53765849365, 11570.247933884313, 11578.47896844644, 11328.0, 11362.431878406129, 11397.715057123556, 11407.08743300534, 11414.086873050135, 11272.0, 11348.205625606211, 11354.432278386048, 11362.0, 11367.488463019185, 45381.09237815225, 45554.40559580432, 45590.31614525785, 45594.0, 45635.77182114102, 4244.702870799046, 4527.705699129546, 4634.72191668501, 4648.817576843784, 4724.503927087675, 35452.468212976826, 35520.28959420797, 35948.0, 36873.81563092194, 37195.62804371949, 4459.44256967879, 4539.818407263706, 4556.931646025312, 4567.903703111229, 4682.438107427097, 377951.0, 379112.0, 379657.0, 380429.0, 381280.0, 38537.614623853966, 64481.355186448476, 64639.353606464276, 64796.0, 65010.67494662497, 38712.419313710314, 40270.59729402727, 40335.19329613391, 40546.797266013484, 40602.390964135564, 14308.213048282236, 14383.928080359532, 14391.136531808124, 14392.85607143936, 14434.639134021734, 13680.110792798436, 14938.925305373621, 15019.173945433105, 15133.848661513464, 15156.09063456174, 9043.050479699668, 9165.81668366629, 9217.723468295962, 9235.1688348049, 9317.068293170765, 3446.293509830529, 3511.613722490524, 3513.3149035937745, 3553.9111522211633, 3594.0835087052633, 1106.446776611694, 1137.5677242647741, 1163.3020187887369, 1224.063591352616, 1244.1912756708211, 33167.3366532668, 35189.24945132426, 35294.0, 35433.27950565205, 35750.96116830046, 1171.619223752284, 1187.6615164678026, 1213.7026428525069, 1257.3587470389984, 1272.5546058879318, 18319.633607327778, 18518.722219166724, 18643.44069677912, 18717.81282187161, 18792.342268020555, 613800.0, 614966.0, 619521.0, 620083.0, 620736.0, 64402.0, 64677.353226468076, 64796.67601661962, 64851.0, 65069.349306507276, 1352.2359866675297, 1412.8022076909242, 1460.8758255548184, 1490.8285547162216, 1496.9101853888583, 34658.48012279868, 37241.441378378804, 37364.439533406476, 37551.0, 37580.0, 54326.45673543372, 54726.72636636791, 54772.17841732457, 54843.725781370835, 54851.17723234234, 407910.0, 410299.0, 411107.0, 412866.0, 413770.0, 51173.48826511836, 51493.227601585255, 51654.741726291126, 51778.74110629423, 52396.21405678994, 54199.45800541944, 54549.0, 54663.45336546743, 55123.0, 55228.723856380464, 45034.0, 47230.0, 47610.285845713035, 47847.76076119597, 48304.516954830004, 44783.32825007693, 46276.30585541152, 46539.76730116328, 46855.76572117118, 48220.0, 46650.53349466462, 46791.532084678714, 47106.528934710215, 48048.519514805805, 48089.75955120202, 38261.4260786094, 41931.0, 42185.57814421816, 42243.0, 42511.78744106383, 31103.222419439695, 31340.529892052095, 31723.682763172073, 32188.678113219506, 32242.0], "value": 21320.917282372546, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.907901643114, 426.86231996283107, 428.8584767026881, 439.5933761270825, 442.7365717398147, 16819.915900420496, 21009.47476313093, 21429.678554821672, 21450.89274553625, 21513.892430537864, 19650.508737281576, 20037.1985120595, 20066.799332006674, 20098.799012009913, 20297.39107826764, 5585.245991791117, 5879.67661778602, 5916.112583112525, 5923.348431672513, 5960.7317670704815, 5751.281089863767, 5898.1447690084915, 5928.3775203603645, 5931.436513531207, 5932.2584676915385, 5892.0, 5921.496672782819, 5923.28920529537, 5927.051671732524, 5934.317553481362, 5724.427557244284, 5809.82570522885, 5898.439648233411, 5904.261967254093, 5906.675132867669, 5716.485516303511, 5744.454276843693, 5748.16651585522, 5748.942510574882, 5750.7412166452195, 13443.327833608379, 13477.191368517822, 13488.79766803489, 13563.118397304144, 13590.456381744718, 2559.4881023795315, 2633.9033969269335, 2760.1581517637114, 2798.3004248937764, 2869.856507174633, 10038.749031274203, 10234.948825255826, 10290.279680422373, 13606.795898061537, 15779.13214773187, 1989.2196484146752, 2002.869813462135, 2011.1352118589, 2054.3528788431745, 2073.5749171419975, 71613.64193179052, 81453.59273203656, 82316.58841705813, 82674.9999999994, 87244.56377718071, 11293.322400655987, 11657.417129143678, 12118.939405303094, 12143.635690929286, 12189.756204875854, 17009.40467083671, 17011.914940425468, 17021.063841488838, 17027.148642567823, 17057.402990895258, 8638.481691098554, 8784.516851573097, 8955.373123881265, 9081.318901082444, 9124.726258212264, 8046.517208967481, 8097.554634495159, 8180.100188979208, 8249.917500825035, 8444.282236009885, 4528.932066019012, 4574.656900732459, 4597.241455159928, 4954.826581069645, 5196.68819870809, 1808.8061879159955, 1861.4415675297357, 2018.777934427212, 2053.835693144545, 2169.5986242545177, 1012.6481147667946, 1020.3469779341227, 1074.2909679611419, 1077.2405454154803, 1085.5549224817787, 21393.67909481359, 22201.44496387603, 22226.555468890532, 22265.777342226695, 22331.21840735599, 1029.9897001029894, 1074.3070719385953, 1080.195254535376, 1088.542812018954, 1116.938568378747, 11328.376939268419, 12200.389980500939, 12209.450574724158, 12325.815112773316, 12343.444544995496, 522934.0, 528687.0, 531519.0, 532603.0, 536405.0, 95453.0, 100715.0, 101559.49220253999, 101766.49116754369, 102771.0, 1071.8820929697727, 1082.1288862465633, 1115.70991542198, 1116.7598966222301, 1128.7968165730247, 18829.24683012719, 19117.426477205707, 19296.614067718285, 19352.8064719351, 19536.60926781485, 33814.830925845214, 41768.373474397304, 46089.53910460987, 46441.76779116218, 46441.981271217264, 62952.37047629465, 64708.0, 67688.6615566919, 68370.0, 69284.0, 8908.777280567778, 9066.138716821892, 9155.176034156691, 9201.30990175753, 9446.244300456085, 12136.635900922987, 13295.069345145746, 13568.59294221175, 13736.038477306893, 13815.723685526435, 11285.322880627189, 11369.602063927723, 11502.19484636101, 11905.642830715093, 12265.509379625071, 11309.660710178709, 11662.067034637039, 11712.531498739954, 11787.292762434281, 11995.520179192734, 11890.346030968034, 11959.820602691141, 12015.81976270374, 12052.457639406422, 12247.326397048248, 8943.463392196489, 9034.548272586477, 9042.638294468148, 9163.404378715362, 9304.767380815534, 11039.227254092137, 11689.941550292195, 12011.279323240633, 12115.818262726243, 12411.379431028232], "value": 9630.083557147282, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1399.9580012599627, 1560.1263292556168, 1596.0, 1800.5858652509921, 1925.653382391169, 8335.333173346133, 9376.484293363863, 9725.854112188304, 9772.364796288246, 10037.899621003788, 9528.475933823636, 9610.0, 9621.663241786538, 9635.0, 9742.951285243582, 2544.6819147606548, 2563.4488584954233, 2571.1001149597587, 2572.524083044643, 2579.496998085374, 2518.64738936549, 2588.9741102588923, 2594.4292255703713, 2597.493488769691, 2602.8958841646313, 2543.55487789637, 2550.1202085280547, 2569.6916370035624, 2576.716561178269, 2577.6777902762155, 2422.358075110097, 2424.612062070069, 2426.9271921842374, 2456.803455723538, 2458.803295736355, 2449.8407603505893, 2451.754824517534, 2458.6434966929874, 2461.027893981885, 2495.05188028548, 9486.383385080017, 9958.302918795687, 9997.950010249975, 10002.499875006293, 10023.69928902134, 5671.2060311556415, 5790.68151251681, 5795.9420405796245, 5796.391378905197, 5799.884002319972, 3717.219383929378, 3721.813909304524, 3787.2425514896854, 3792.260509200707, 3801.6388443097862, 1161.3148242536943, 1162.343276049038, 1164.7612239490884, 1184.9822252666218, 1185.342135115011, 14075.929620351935, 14093.365798539091, 14157.433702651882, 14170.149791012469, 14178.574642760837, 4901.068796928572, 4973.204287314013, 5004.3994720633755, 5062.0, 5151.639385243072, 10978.341299522055, 10996.450177491253, 10997.835032474519, 11000.779984400267, 11001.889981100247, 5782.508486778587, 5804.100364443518, 5804.448577385142, 5813.447722466359, 5829.329627092939, 4751.501092385319, 4781.6891902026255, 5287.41838397776, 5334.866628334323, 5412.702301373462, 3996.8001599920467, 4127.360259159835, 4136.4415803866095, 4157.688173387008, 4240.809163587646, 1177.5289884046279, 1185.146694380045, 1196.2642974570576, 1202.851277030434, 1226.258113841131, 1034.2863424237403, 1036.3781730961514, 1039.7660526381503, 1045.8692663417073, 1053.4995876958537, 6183.598066125687, 6336.619802811846, 6504.0, 6518.641474718841, 6582.703778329987, 502.50252250271967, 513.4044508370273, 518.636954132109, 521.559282406366, 534.7700488789858, 4585.0, 4682.274247491644, 4753.738544380092, 4758.547937945958, 4830.227163653869, 11880.227785194107, 12506.187097838794, 12611.0, 12645.0, 12680.112392132647, 11133.721656958642, 12538.122331436894, 12555.372231388394, 12573.182743121668, 12585.37073146339, 8823.382363234641, 8977.0, 8999.10008999108, 9015.864762028576, 9027.593758281024, 6658.300878407891, 6673.599584024974, 6801.251862295045, 6891.931080689129, 6997.335253150943, 10491.160707143563, 10649.201309901633, 10649.414282214553, 10759.731006724895, 10775.353478791298, 10390.792184156426, 11048.0, 11145.55417783312, 11228.0, 11309.208355415041, 8497.702580409656, 8783.472991620523, 8814.51520166397, 8894.777630559287, 8914.55427228649, 9644.324897257125, 9713.660021899199, 9773.267004974514, 9781.266405019795, 9784.0, 8377.70678026266, 8448.957755211184, 8497.192766687154, 8512.0, 8834.514101724468, 8487.321014318717, 8528.829423411622, 8609.311255099452, 8637.8704319434, 8812.86780698303, 7324.08448943882, 7454.962725186339, 7562.546247225184, 7719.459637825523, 7931.722389716563, 5731.455511726379, 5769.07694768835, 5824.592278540462, 5845.912311315248, 5902.852428689317, 5840.8247752567495, 5849.5612829037145, 5866.7439571038485, 5894.646321220741, 5985.072313791369], "value": 4985.310035088233, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.1998862667045, 345.93254315408495, 348.85348153775413, 350.90525558099296, 358.64315006568467, 5498.917516237255, 5545.112781954888, 5552.444755524445, 5555.472230138135, 5617.157426386034, 5372.2210279509445, 5432.375276843164, 5503.504684578387, 5526.392096869341, 5542.725173210164, 3872.186840763443, 4071.5114186297537, 4329.350597410398, 4345.391645169679, 4350.825966961318, 4343.891402714941, 4344.370066340379, 4345.630621397185, 4345.869623911288, 4407.457389913537, 4115.464989551369, 4325.221460137174, 4340.891477713051, 4369.213541562517, 4375.978120109395, 4026.9194616107516, 4239.342901850203, 4259.531451540343, 4358.171947329997, 4368.257396242647, 3829.6553310202216, 3843.442700808395, 3846.2499812536566, 3882.2235552889533, 3914.0, 5245.344331958505, 5316.3620365556, 5317.867053323659, 5384.596155288369, 5388.353397592275, 4868.68545492715, 4880.072786170616, 4882.829100981448, 4923.4091908970795, 4945.480724523945, 5647.830565083013, 5650.830475085754, 5664.745086471119, 5665.2351932489, 5684.175794509773, 3886.9222615547815, 4073.6333729964445, 4185.267578173838, 4205.495340559122, 4229.942514371407, 5640.774369025234, 5821.18503409523, 5936.833055934596, 5966.701664916737, 5980.910286345665, 4136.482939632546, 4154.127633197062, 4157.521884983205, 4191.350340697197, 4203.07532342884, 4267.893302667459, 4268.701190916669, 4271.615554600101, 4272.743635381887, 4273.957260427418, 4624.907501849944, 4872.561469467765, 4896.2410826321975, 4905.337779399735, 4906.288588154697, 5742.913856292159, 5743.368229494752, 5761.366249712528, 5765.279340082489, 5838.562107841929, 1460.4450308882556, 1476.4463326252655, 1477.9778303325459, 1481.1187343530582, 1491.4854375240525, 915.8385919102587, 932.6129656192604, 933.7208025005785, 940.7648087978006, 950.5532399772096, 4952.702837829742, 4965.751712414437, 4992.377477319886, 5021.246812978009, 5070.112730272262, 5194.4805519447755, 5197.142471492161, 5209.7916083356995, 5224.555912747383, 5233.947660523422, 4734.928976065362, 4987.326710893983, 4998.750062496861, 5011.3986321640905, 5093.326003847936, 2786.4984302825687, 2802.691703912568, 2806.7052959439375, 2807.3122085088876, 2815.1982731052144, 5402.7583449929725, 5408.45915408456, 5429.041172560964, 5442.265294185314, 5468.425815289337, 5137.845864624067, 5247.868803279948, 5285.2600635911, 5295.735213239323, 5344.690550815075, 5347.732613369316, 5372.946270537323, 5379.892402151857, 5387.3265841769, 5407.810726624549, 5201.557867581147, 5229.816956406507, 5308.920366194588, 5320.446185830746, 5356.223347614652, 4865.756712164449, 5088.7940597423385, 5105.2342148678, 5187.04039752647, 5203.895922081613, 5314.272861320163, 5793.333766616893, 5926.4666180044005, 6086.665233412028, 6095.299040610386, 4230.7884605770205, 4242.830286788494, 4266.466691663542, 4277.700560960703, 4296.775419005568, 5736.741846616996, 5951.434613711691, 5999.4900433463645, 6003.519718422603, 6004.939950600438, 5742.966266072147, 5746.367899531132, 5748.252727145445, 5767.279090113736, 5772.6536407815665, 5716.228309178374, 5741.741621626953, 5754.136879468113, 5761.214023652681, 5802.448767367094, 5464.863378415572, 5676.46735381453, 5681.4034526374135, 5693.202951586864, 5698.230738850368, 5733.799317023884, 5737.91393129095, 5742.885142297214, 5746.195532625352, 5774.314943551269, 5643.407442218508, 5656.490915817596, 5697.350731909516, 5699.857503562444, 5758.280214973129], "value": 4260.546036304093, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [856.9014563325219, 870.0820634230884, 892.2371372476529, 935.9485228312449, 953.837847565914, 16060.27728752206, 17195.74206386902, 17224.0, 17226.913865430655, 17227.138643067858, 16456.83543164568, 16600.169991500436, 16612.750808737877, 16613.41853035144, 16627.251773670185, 733.6808488307603, 861.9569021548898, 891.4473026723441, 894.2622336572354, 901.5852707754408, 912.3431129586687, 914.437620863171, 914.6112902016649, 915.7069737683944, 916.0290092501936, 959.4195511715382, 960.5005397193455, 960.7117864640614, 960.9471479068649, 961.1781926452852, 783.34199272611, 915.1260546178416, 938.1369140390857, 949.9620015199383, 950.396498223626, 700.4676445901101, 701.6000879498637, 701.828052127227, 702.6381413572014, 702.9894551581731, 15565.45520906774, 15607.0, 15614.375424982987, 15626.843731562767, 15627.374905003786, 14658.486952956595, 14661.633459163606, 14853.108813471226, 14861.777073343801, 15006.0, 13593.660158496117, 13934.93032534841, 14213.360398782079, 14269.00116991811, 14287.928560357235, 4730.62155027597, 4749.691270067435, 4766.142094423002, 4843.24929635907, 4851.442084160331, 83820.99999999939, 84689.57655211685, 85390.0, 86497.56751216203, 86587.0, 11779.52881884715, 12088.0, 12192.0, 12371.938140309241, 12537.811932821014, 23978.16076437351, 24123.155689550782, 24130.0, 24130.7586924132, 24136.51726965451, 9039.502827344559, 9048.0, 9160.0, 9284.118008789153, 9452.95273523628, 4843.782029808667, 4895.657303988758, 4914.533119353656, 4983.825566105169, 5160.458151894072, 3749.850005999784, 3877.185790983925, 3879.86420475282, 3881.3983832506005, 3887.844486220576, 677.2347247610169, 685.348918527391, 713.7466199499207, 721.4012369733109, 729.4310437858479, 706.1067749297143, 725.2747252747253, 750.4634186556639, 798.217746608327, 856.3277826905849, 10174.4912754362, 10404.271700980858, 10713.303635263683, 10756.031957123896, 10950.945245273722, 749.5914726474085, 766.6473422225727, 793.6745934166964, 808.7048227397075, 822.457178262344, 5259.132243179833, 5353.705546194997, 5428.511433970962, 5435.076037073707, 5437.592180586472, 126837.0, 132673.33663331813, 133722.0, 134186.32906835404, 134390.3280483611, 113310.43344783389, 114018.0, 117452.0, 118485.4075729633, 118886.0, 91192.54403727938, 97697.0, 98650.0, 99733.50133249287, 100014.99999999854, 10083.294169408071, 10302.536385862804, 10312.896871031493, 10372.688819335432, 10428.322159059637, 56115.71942140263, 58133.63997700274, 58146.7092664534, 59450.0, 60222.698886505284, 80700.59649701713, 85826.57086714526, 86848.0, 87498.9447967299, 88457.55771221103, 12959.870401296244, 13029.218246905217, 13163.276019819003, 13242.668933276746, 13382.330883455594, 14441.422343106158, 14560.19918904428, 15129.39482420691, 15268.160251186291, 15609.141497217763, 13397.53108641193, 13622.591322260349, 13701.0, 13906.443742250602, 14186.787198192242, 12960.740785184433, 13005.0, 13249.0, 13404.597862064154, 13858.0, 13478.932605336911, 13522.25627590492, 13697.0, 13847.861521384657, 14608.196549189897, 10589.788204236027, 10985.340879546933, 11098.5560577576, 11222.102231821598, 11359.943200283946, 11416.257943233662, 11553.537858485566, 11589.536418543164, 11716.94141529287, 11757.353345566076], "value": 8258.70410235587, "unit": "score"}], "name": "dromaeo_css", "value": 7665.158349491794, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 118.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 114.0, 145.0, 163.0, 116.0, 136.0, 147.0, 119.0, 174.0, 117.0], "value": 138.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 161.0, 161.0, 162.0, 161.0, 158.0, 161.0, 162.0, 159.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 188.0, 74.0, 73.0, 73.0, 73.0, 162.0, 74.0], "value": 94.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 162.0, 84.0, 84.0, 83.0, 145.0, 83.0, 83.0, 145.0], "value": 103.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 148.0, 148.0, 147.0, 146.0, 146.0, 148.0, 147.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 49.0, 50.0, 50.0, 50.0, 50.0, 49.0, 50.0, 52.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 60.0, 61.0, 60.0, 60.0, 61.0, 62.0, 61.0, 61.0], "value": 61.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 72.0, 72.0, 72.0, 73.0, 73.0, 81.0, 74.0, 74.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 117.0, 117.0, 124.0, 117.0, 115.0, 117.0, 115.0, 144.0, 122.0], "value": 120.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 152.0, 152.0, 156.0, 153.0, 153.0, 155.0, 153.0, 150.0, 155.0], "value": 153.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 63.0, 61.0, 61.0, 64.0, 64.0, 61.0, 60.0], "value": 62.0, "unit": "score"}], "name": "kraken", "value": 1485.0, "alertThreshold": 2.0}]} 07:47:14 INFO - Return code: 0 07:47:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:47:14 INFO - # TBPL SUCCESS # 07:47:14 INFO - Running post-action listener: _resource_record_post_action 07:47:14 INFO - [mozharness: 2016-09-17 14:47:14.361445Z] Finished run-tests step (success) 07:47:14 INFO - Running post-run listener: _resource_record_post_run 07:47:14 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 765952; Write bytes: 329256960; Read time: 204; Write time: 229368 07:47:14 INFO - TinderboxPrint: CPU usage
13.0% 07:47:14 INFO - TinderboxPrint: I/O read bytes / time
765,952 / 204 07:47:14 INFO - TinderboxPrint: I/O write bytes / time
329,256,960 / 229,368 07:47:14 INFO - TinderboxPrint: CPU idle
8,257.1 (87.0%) 07:47:14 INFO - TinderboxPrint: CPU user
1,189.5 (12.5%) 07:47:14 INFO - TinderboxPrint: Swap in / out
0 / 0 07:47:14 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78483456; Read time: 0; Write time: 89852 07:47:14 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 761856; Write bytes: 249802752; Read time: 204; Write time: 139484 07:47:14 INFO - Running post-run listener: _upload_blobber_files 07:47:14 INFO - Blob upload gear active. 07:47:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:47:14 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:47:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:47:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:47:15 INFO - (blobuploader) - INFO - Open directory for files ... 07:47:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:47:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:47:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:47:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:47:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:47:16 INFO - (blobuploader) - INFO - Done attempting. 07:47:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:47:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:47:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:47:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:47:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:47:16 INFO - (blobuploader) - INFO - Done attempting. 07:47:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:47:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:47:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:47:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:47:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:47:20 INFO - (blobuploader) - INFO - Done attempting. 07:47:20 INFO - (blobuploader) - INFO - Iteration through files over. 07:47:20 INFO - Return code: 0 07:47:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:47:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:47:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8876e0981ef180fc2bb26764885b591d0ad0e71603bc9627bc65d8d790da291d2e2501e18a0f2ea1321e2d73400cce5bb31a09c524263ab48d9f28db17bb7662", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/563360c9d02533f05a42ec6a93c27d5853e090e18a38ff57c46646117faa74b94bfabcb5583406c573a3d2b1c5be792dc9e8c98a7ae347c2f6f3a2b3b6609cda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/854d587dbfb1662f2f71e565d4dec8eebc781b89d57c98bbaf750b276f0d123e92eeafd42a4c7210b1103561b0dfe54ff702ba7439f648b81c176b9649b4eeca"} 07:47:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:47:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:47:20 INFO - Contents: 07:47:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8876e0981ef180fc2bb26764885b591d0ad0e71603bc9627bc65d8d790da291d2e2501e18a0f2ea1321e2d73400cce5bb31a09c524263ab48d9f28db17bb7662", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/563360c9d02533f05a42ec6a93c27d5853e090e18a38ff57c46646117faa74b94bfabcb5583406c573a3d2b1c5be792dc9e8c98a7ae347c2f6f3a2b3b6609cda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/854d587dbfb1662f2f71e565d4dec8eebc781b89d57c98bbaf750b276f0d123e92eeafd42a4c7210b1103561b0dfe54ff702ba7439f648b81c176b9649b4eeca"} 07:47:20 INFO - Running post-run listener: copy_logs_to_upload_dir 07:47:20 INFO - Copying logs to upload dir... 07:47:20 INFO - mkdir: /builds/slave/test/build/upload/logs 07:47:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.145627 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-17 07:47:20.025053) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:47:20.028446) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8876e0981ef180fc2bb26764885b591d0ad0e71603bc9627bc65d8d790da291d2e2501e18a0f2ea1321e2d73400cce5bb31a09c524263ab48d9f28db17bb7662", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/563360c9d02533f05a42ec6a93c27d5853e090e18a38ff57c46646117faa74b94bfabcb5583406c573a3d2b1c5be792dc9e8c98a7ae347c2f6f3a2b3b6609cda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/854d587dbfb1662f2f71e565d4dec8eebc781b89d57c98bbaf750b276f0d123e92eeafd42a4c7210b1103561b0dfe54ff702ba7439f648b81c176b9649b4eeca"} build_url:https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011195 build_url: 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8876e0981ef180fc2bb26764885b591d0ad0e71603bc9627bc65d8d790da291d2e2501e18a0f2ea1321e2d73400cce5bb31a09c524263ab48d9f28db17bb7662", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/563360c9d02533f05a42ec6a93c27d5853e090e18a38ff57c46646117faa74b94bfabcb5583406c573a3d2b1c5be792dc9e8c98a7ae347c2f6f3a2b3b6609cda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/854d587dbfb1662f2f71e565d4dec8eebc781b89d57c98bbaf750b276f0d123e92eeafd42a4c7210b1103561b0dfe54ff702ba7439f648b81c176b9649b4eeca"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:47:20.054030) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:47:20.054405) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1474121873.648894-552078323 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004353 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:47:20.139067) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:47:20.139426) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:47:20.139737) ========= ========= Total master_lag: 2.72 =========