builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-048 starttime: 1472585817.03 results: success (0) buildid: 20160830093921 builduid: 86ba142624df48b3b49a4204efa8ff7c revision: c819e284a13937fc955c2efb6da7fdf9c5bb8b88 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 12:36:57.026551) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 12:36:57.027035) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 12:36:57.027342) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 12:36:57.042393) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 12:36:57.042875) ========= 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=fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-30 12:36:57-- 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% 227M=0s 2016-08-30 12:36:57 (227 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.191165 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 12:36:57.248802) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 12:36:57.249154) ========= 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=fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015772 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 12:36:57.300800) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 12:36:57.301200) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c819e284a13937fc955c2efb6da7fdf9c5bb8b88 --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 c819e284a13937fc955c2efb6da7fdf9c5bb8b88 --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=fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-30 12:36:57,411 truncating revision to first 12 chars 2016-08-30 12:36:57,411 Setting DEBUG logging. 2016-08-30 12:36:57,411 attempt 1/10 2016-08-30 12:36:57,411 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c819e284a139?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 12:36:58,592 unpacking tar archive at: mozilla-aurora-c819e284a139/testing/mozharness/ program finished with exit code 0 elapsedTime=1.526188 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 12:36:58.845297) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 12:36:58.846089) ========= script_repo_revision: c819e284a13937fc955c2efb6da7fdf9c5bb8b88 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 12:36:58.847063) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 12:36:58.847367) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 12:36:58.859572) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-08-30 12:36:58.859894) ========= /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=fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:36:59 INFO - MultiFileLogger online at 20160830 12:36:59 in /builds/slave/test 12:36:59 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 12:36:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:36:59 INFO - {'append_to_log': False, 12:36:59 INFO - 'base_work_dir': '/builds/slave/test', 12:36:59 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:36:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:36:59 INFO - 'branch': 'Mozilla-Aurora', 12:36:59 INFO - 'buildbot_json_path': 'buildprops.json', 12:36:59 INFO - 'config_files': ('talos/linux_config.py',), 12:36:59 INFO - 'default_actions': ('clobber', 12:36:59 INFO - 'read-buildbot-config', 12:36:59 INFO - 'download-and-extract', 12:36:59 INFO - 'populate-webroot', 12:36:59 INFO - 'create-virtualenv', 12:36:59 INFO - 'install', 12:36:59 INFO - 'run-tests'), 12:36:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:36:59 INFO - 'download_minidump_stackwalk': True, 12:36:59 INFO - 'download_symbols': 'ondemand', 12:36:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:36:59 INFO - 'tooltool.py': '/tools/tooltool.py', 12:36:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:36:59 INFO - '/tools/misc-python/virtualenv.py')}, 12:36:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:36:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:36:59 INFO - 'installer_path': 'installer.exe', 12:36:59 INFO - 'log_level': 'info', 12:36:59 INFO - 'log_name': 'talos', 12:36:59 INFO - 'log_to_console': True, 12:36:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:36:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:36:59 INFO - 'opt_config_files': (), 12:36:59 INFO - 'pip_index': False, 12:36:59 INFO - 'sps_profile': False, 12:36:59 INFO - 'sps_profile_interval': 0, 12:36:59 INFO - 'suite': 'dromaeojs', 12:36:59 INFO - 'system_bits': '32', 12:36:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:36:59 INFO - 'title': 'talos-linux64-ix-048', 12:36:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:36:59 INFO - 'use_talos_json': True, 12:36:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:36:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:36:59 INFO - 'work_dir': 'build'} 12:36:59 INFO - [mozharness: 2016-08-30 19:36:59.050335Z] Running clobber step. 12:36:59 INFO - Running pre-action listener: _resource_record_pre_action 12:36:59 INFO - Running main action method: clobber 12:36:59 INFO - rmtree: /builds/slave/test/build 12:36:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:36:59 INFO - Running post-action listener: _resource_record_post_action 12:36:59 INFO - [mozharness: 2016-08-30 19:36:59.501324Z] Finished clobber step (success) 12:36:59 INFO - [mozharness: 2016-08-30 19:36:59.501423Z] Running read-buildbot-config step. 12:36:59 INFO - Running pre-action listener: _resource_record_pre_action 12:36:59 INFO - Running main action method: read_buildbot_config 12:36:59 INFO - Using buildbot properties: 12:36:59 INFO - { 12:36:59 INFO - "project": "", 12:36:59 INFO - "product": "firefox", 12:36:59 INFO - "script_repo_revision": "production", 12:36:59 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 12:36:59 INFO - "repository": "", 12:36:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 12:36:59 INFO - "buildid": "20160830093921", 12:36:59 INFO - "pgo_build": "True", 12:36:59 INFO - "basedir": "/builds/slave/test", 12:36:59 INFO - "buildnumber": 705, 12:36:59 INFO - "slavename": "talos-linux64-ix-048", 12:36:59 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:36:59 INFO - "platform": "ubuntu64_hw", 12:36:59 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 12:36:59 INFO - "branch": "mozilla-aurora", 12:36:59 INFO - "repo_path": "releases/mozilla-aurora", 12:36:59 INFO - "revision": "c819e284a13937fc955c2efb6da7fdf9c5bb8b88", 12:36:59 INFO - "stage_platform": "linux64-pgo", 12:36:59 INFO - "builduid": "86ba142624df48b3b49a4204efa8ff7c", 12:36:59 INFO - "slavebuilddir": "test" 12:36:59 INFO - } 12:36:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 12:36:59 INFO - Running post-action listener: _resource_record_post_action 12:36:59 INFO - [mozharness: 2016-08-30 19:36:59.503826Z] Finished read-buildbot-config step (success) 12:36:59 INFO - [mozharness: 2016-08-30 19:36:59.503915Z] Running download-and-extract step. 12:36:59 INFO - Running pre-action listener: _resource_record_pre_action 12:36:59 INFO - Running main action method: download_and_extract 12:36:59 INFO - mkdir: /builds/slave/test/build/tests 12:36: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')]} 12:36:59 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:36:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 12:36:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 12:36:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:36:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:36:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 12:36:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:36:59 INFO - retry: Failed, sleeping 30 seconds before retrying 12:37:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:37:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 12:37:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:37:29 INFO - retry: Failed, sleeping 60 seconds before retrying 12:38:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:38:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 12:38:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:38:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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! 12:38:29 INFO - Caught exception: HTTP Error 404: Not Found 12:38:29 INFO - Caught exception: HTTP Error 404: Not Found 12:38:29 INFO - Caught exception: HTTP Error 404: Not Found 12:38:29 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 12:38:29 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:38:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:38:30 INFO - Downloaded 1361 bytes. 12:38:30 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 12:38:30 INFO - Using the following test package requirements: 12:38:30 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 12:38:30 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 12:38:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 12:38:30 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 12:38:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 12:38:30 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 12:38:30 INFO - u'jsshell-linux-x86_64.zip'], 12:38:30 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 12:38:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 12:38:30 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 12:38:30 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 12:38:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 12:38:30 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 12:38:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 12:38:30 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 12:38:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 12:38:30 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 12:38:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 12:38:30 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:38: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')]} 12:38:30 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:38:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 12:38:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 12:38:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:38:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:38:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 12:38:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:38:30 INFO - retry: Failed, sleeping 30 seconds before retrying 12:39:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:39:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 12:39:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:39:00 INFO - retry: Failed, sleeping 60 seconds before retrying 12:40:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:40:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 12:40:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:40:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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! 12:40:00 INFO - Caught exception: HTTP Error 404: Not Found 12:40:00 INFO - Caught exception: HTTP Error 404: Not Found 12:40:00 INFO - Caught exception: HTTP Error 404: Not Found 12:40:00 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 12:40:00 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:40:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:40:01 INFO - Downloaded 21769265 bytes. 12:40:01 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'] 12:40:01 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 12:40:02 INFO - Return code: 0 12:40:02 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 12:40:02 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')]} 12:40:02 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:40:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 12:40:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 12:40:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:40:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:40:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 12:40:02 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:40:02 INFO - retry: Failed, sleeping 30 seconds before retrying 12:40:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:40:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 12:40:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:40:32 INFO - retry: Failed, sleeping 60 seconds before retrying 12:41:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:41:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 12:41:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:41:32 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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! 12:41:32 INFO - Caught exception: HTTP Error 404: Not Found 12:41:32 INFO - Caught exception: HTTP Error 404: Not Found 12:41:32 INFO - Caught exception: HTTP Error 404: Not Found 12:41:32 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 12:41:32 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:41:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:41:34 INFO - Downloaded 21769265 bytes. 12:41:34 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'] 12:41:34 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 12:41:35 INFO - Return code: 0 12:41:35 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')]} 12:41:35 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:41:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 12:41:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 12:41:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:41:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:41:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 12:41:35 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:41:35 INFO - retry: Failed, sleeping 30 seconds before retrying 12:42:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:42:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 12:42:05 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:42:05 INFO - retry: Failed, sleeping 60 seconds before retrying 12:43:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:43:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 12:43:05 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:43:05 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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! 12:43:05 INFO - Caught exception: HTTP Error 404: Not Found 12:43:05 INFO - Caught exception: HTTP Error 404: Not Found 12:43:05 INFO - Caught exception: HTTP Error 404: Not Found 12:43:05 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 12:43:05 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:43:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:43:06 INFO - Downloaded 14413723 bytes. 12:43:06 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'] 12:43:06 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 12:43:06 INFO - Return code: 0 12:43:06 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')]} 12:43:06 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:43:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 12:43:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 12:43:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:43:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:43:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 12:43:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:43:06 INFO - retry: Failed, sleeping 30 seconds before retrying 12:43:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:43:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 12:43:36 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:43:36 INFO - retry: Failed, sleeping 60 seconds before retrying 12:44:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:44:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 12:44:36 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:44:36 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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! 12:44:36 INFO - Caught exception: HTTP Error 404: Not Found 12:44:36 INFO - Caught exception: HTTP Error 404: Not Found 12:44:36 INFO - Caught exception: HTTP Error 404: Not Found 12:44:36 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 12:44:36 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:44:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 12:44:38 INFO - Downloaded 63875997 bytes. 12:44:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 12:44:38 INFO - mkdir: /builds/slave/test/properties 12:44:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:44:38 INFO - Writing to file /builds/slave/test/properties/build_url 12:44:38 INFO - Contents: 12:44:38 INFO - build_url:https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 12:44:38 INFO - Running post-action listener: _resource_record_post_action 12:44:38 INFO - Running post-action listener: set_extra_try_arguments 12:44:38 INFO - [mozharness: 2016-08-30 19:44:38.725416Z] Finished download-and-extract step (success) 12:44:38 INFO - [mozharness: 2016-08-30 19:44:38.725646Z] Running populate-webroot step. 12:44:38 INFO - Running pre-action listener: _resource_record_pre_action 12:44:38 INFO - Running main action method: populate_webroot 12:44:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:44:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:44:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:44:38 INFO - '--tppagecycles', 12:44:38 INFO - '7'], 12:44:38 INFO - 'tests': ['tsvgm']}}, 12:44:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:44:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:44:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:44:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:44:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:44:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:44:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:44:38 INFO - 'talos_options': ['--disable-e10s'], 12:44:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:44:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:44:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:44:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:44:38 INFO - 'talos_options': ['--disable-e10s'], 12:44:38 INFO - 'tests': ['damp', 'tps']}, 12:44:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:44:38 INFO - 'tests': ['damp', 'tps']}, 12:44:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:44:38 INFO - 'tests': ['dromaeo_dom']}, 12:44:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:44:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:44:38 INFO - 'tests': ['basic_compositor_video']}, 12:44:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:44:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:44:38 INFO - 'tests': ['a11yr', 12:44:38 INFO - 'ts_paint', 12:44:38 INFO - 'tpaint', 12:44:38 INFO - 'sessionrestore', 12:44:38 INFO - 'sessionrestore_no_auto_restore', 12:44:38 INFO - 'tabpaint']}, 12:44:38 INFO - 'other-e10s': {'tests': ['a11yr', 12:44:38 INFO - 'ts_paint', 12:44:38 INFO - 'tpaint', 12:44:38 INFO - 'sessionrestore', 12:44:38 INFO - 'sessionrestore_no_auto_restore', 12:44:38 INFO - 'tabpaint']}, 12:44:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:44:38 INFO - 'ts_paint', 12:44:38 INFO - 'tpaint', 12:44:38 INFO - 'sessionrestore', 12:44:38 INFO - 'sessionrestore_no_auto_restore', 12:44:38 INFO - 'tabpaint']}, 12:44:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:44:38 INFO - 'ts_paint', 12:44:38 INFO - 'tpaint', 12:44:38 INFO - 'sessionrestore', 12:44:38 INFO - 'sessionrestore_no_auto_restore', 12:44:38 INFO - 'tabpaint']}, 12:44:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:44:38 INFO - 'tests': ['a11yr', 12:44:38 INFO - 'ts_paint', 12:44:38 INFO - 'tpaint', 12:44:38 INFO - 'sessionrestore', 12:44:38 INFO - 'sessionrestore_no_auto_restore', 12:44:38 INFO - 'tabpaint']}, 12:44:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:44:38 INFO - 'tests': ['a11yr', 12:44:38 INFO - 'ts_paint', 12:44:38 INFO - 'tpaint', 12:44:38 INFO - 'sessionrestore', 12:44:38 INFO - 'sessionrestore_no_auto_restore', 12:44:38 INFO - 'tabpaint']}, 12:44:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:44:38 INFO - 'tests': ['tsvgx', 12:44:38 INFO - 'tsvgr_opacity', 12:44:38 INFO - 'tart', 12:44:38 INFO - 'tscrollx', 12:44:38 INFO - 'cart']}, 12:44:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:44:38 INFO - 'tsvgr_opacity', 12:44:38 INFO - 'tart', 12:44:38 INFO - 'tscrollx', 12:44:38 INFO - 'cart']}, 12:44:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:44:38 INFO - 'talos_options': ['--disable-e10s'], 12:44:38 INFO - 'tests': ['tp5o']}, 12:44:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:44:38 INFO - 'tests': ['tp5o']}, 12:44:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:44:38 INFO - 'talos_options': ['--disable-e10s', 12:44:38 INFO - '--xperf_path', 12:44:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:44:38 INFO - 'tests': ['tp5n']}, 12:44:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:44:38 INFO - 'talos_options': ['--xperf_path', 12:44:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:44:38 INFO - 'tests': ['tp5n']}}, 12:44:38 INFO - 'talos.zip': {'path': '', 12:44:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:44:38 INFO - Running post-action listener: _resource_record_post_action 12:44:38 INFO - [mozharness: 2016-08-30 19:44:38.743673Z] Finished populate-webroot step (success) 12:44:38 INFO - [mozharness: 2016-08-30 19:44:38.743762Z] Running create-virtualenv step. 12:44:38 INFO - Running pre-action listener: _resource_record_pre_action 12:44:38 INFO - Running main action method: create_virtualenv 12:44:38 INFO - Creating virtualenv /builds/slave/test/build/venv 12:44:38 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 12:44:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:44:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:44:38 INFO - Using real prefix '/usr' 12:44:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:44:39 INFO - Installing distribute.............................................................................................................................................................................................done. 12:44:41 INFO - Installing pip.................done. 12:44:41 INFO - Return code: 0 12:44:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:44:41 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')]} 12:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:41 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')]} 12:44:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:41 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 0x219b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a3b0>, '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 0x245b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245b740>, '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': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', '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 12:44:41 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 12:44:41 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 12:44:41 INFO - Using env: {'DISPLAY': ':0', 12:44:41 INFO - 'HOME': '/home/cltbld', 12:44:41 INFO - 'LANG': 'en_US.UTF-8', 12:44:41 INFO - 'LANGUAGE': 'en_US:en', 12:44:41 INFO - 'LOGNAME': 'cltbld', 12:44:41 INFO - 'MAIL': '/var/mail/cltbld', 12:44:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:41 INFO - 'MOZ_NO_REMOTE': '1', 12:44:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:41 INFO - 'NO_EM_RESTART': '1', 12:44:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:41 INFO - 'PWD': '/builds/slave/test', 12:44:41 INFO - 'SHELL': '/bin/bash', 12:44:41 INFO - 'SHLVL': '1', 12:44:41 INFO - 'TERM': 'linux', 12:44:41 INFO - 'TMOUT': '86400', 12:44:41 INFO - 'USER': 'cltbld', 12:44:41 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', 12:44:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:41 INFO - '_': '/tools/buildbot/bin/python'} 12:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:41 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 12:44:41 INFO - Cleaning up... 12:44:41 INFO - Return code: 0 12:44:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:44:41 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')]} 12:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:41 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')]} 12:44:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:41 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 0x219b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a3b0>, '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 0x245b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245b740>, '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': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', '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 12:44:41 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 12:44:41 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 12:44:41 INFO - Using env: {'DISPLAY': ':0', 12:44:41 INFO - 'HOME': '/home/cltbld', 12:44:41 INFO - 'LANG': 'en_US.UTF-8', 12:44:41 INFO - 'LANGUAGE': 'en_US:en', 12:44:41 INFO - 'LOGNAME': 'cltbld', 12:44:41 INFO - 'MAIL': '/var/mail/cltbld', 12:44:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:41 INFO - 'MOZ_NO_REMOTE': '1', 12:44:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:41 INFO - 'NO_EM_RESTART': '1', 12:44:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:41 INFO - 'PWD': '/builds/slave/test', 12:44:41 INFO - 'SHELL': '/bin/bash', 12:44:41 INFO - 'SHLVL': '1', 12:44:41 INFO - 'TERM': 'linux', 12:44:41 INFO - 'TMOUT': '86400', 12:44:41 INFO - 'USER': 'cltbld', 12:44:41 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', 12:44:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:41 INFO - '_': '/tools/buildbot/bin/python'} 12:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:41 INFO - Downloading/unpacking psutil>=3.1.1 12:44:41 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 12:44:41 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 12:44:41 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 12:44:41 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 12:44:41 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 12:44:41 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 12:44:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:44:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:44:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:44:43 INFO - Installing collected packages: psutil 12:44:43 INFO - Running setup.py install for psutil 12:44:43 INFO - building 'psutil._psutil_linux' extension 12:44:43 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 12:44:43 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 12:44:43 INFO - building 'psutil._psutil_posix' extension 12:44:43 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 12:44:44 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 12:44:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:44:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:44:44 INFO - Successfully installed psutil 12:44:44 INFO - Cleaning up... 12:44:44 INFO - Return code: 0 12:44:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:44:44 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')]} 12:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:44 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')]} 12:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:44 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 0x219b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a3b0>, '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 0x245b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245b740>, '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': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', '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 12:44:44 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 12:44:44 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 12:44:44 INFO - Using env: {'DISPLAY': ':0', 12:44:44 INFO - 'HOME': '/home/cltbld', 12:44:44 INFO - 'LANG': 'en_US.UTF-8', 12:44:44 INFO - 'LANGUAGE': 'en_US:en', 12:44:44 INFO - 'LOGNAME': 'cltbld', 12:44:44 INFO - 'MAIL': '/var/mail/cltbld', 12:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:44 INFO - 'MOZ_NO_REMOTE': '1', 12:44:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:44 INFO - 'NO_EM_RESTART': '1', 12:44:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:44 INFO - 'PWD': '/builds/slave/test', 12:44:44 INFO - 'SHELL': '/bin/bash', 12:44:44 INFO - 'SHLVL': '1', 12:44:44 INFO - 'TERM': 'linux', 12:44:44 INFO - 'TMOUT': '86400', 12:44:44 INFO - 'USER': 'cltbld', 12:44:44 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', 12:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:44 INFO - '_': '/tools/buildbot/bin/python'} 12:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:44:44 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 12:44:44 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 12:44:44 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 12:44:44 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 12:44:44 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 12:44:44 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 12:44:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:44:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:44:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:44:46 INFO - Installing collected packages: mozsystemmonitor 12:44:46 INFO - Running setup.py install for mozsystemmonitor 12:44:46 INFO - Successfully installed mozsystemmonitor 12:44:46 INFO - Cleaning up... 12:44:46 INFO - Return code: 0 12:44:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:44:46 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')]} 12:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:46 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')]} 12:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:46 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 0x219b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a3b0>, '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 0x245b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245b740>, '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': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', '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 12:44:46 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 12:44:46 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 12:44:46 INFO - Using env: {'DISPLAY': ':0', 12:44:46 INFO - 'HOME': '/home/cltbld', 12:44:46 INFO - 'LANG': 'en_US.UTF-8', 12:44:46 INFO - 'LANGUAGE': 'en_US:en', 12:44:46 INFO - 'LOGNAME': 'cltbld', 12:44:46 INFO - 'MAIL': '/var/mail/cltbld', 12:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:46 INFO - 'MOZ_NO_REMOTE': '1', 12:44:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:46 INFO - 'NO_EM_RESTART': '1', 12:44:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:46 INFO - 'PWD': '/builds/slave/test', 12:44:46 INFO - 'SHELL': '/bin/bash', 12:44:46 INFO - 'SHLVL': '1', 12:44:46 INFO - 'TERM': 'linux', 12:44:46 INFO - 'TMOUT': '86400', 12:44:46 INFO - 'USER': 'cltbld', 12:44:46 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', 12:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:46 INFO - '_': '/tools/buildbot/bin/python'} 12:44:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:46 INFO - Downloading/unpacking jsonschema==2.5.1 12:44:46 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 12:44:46 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 12:44:46 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 12:44:46 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 12:44:46 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 12:44:46 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 12:44:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:44:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:44:48 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 12:44:48 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 12:44:48 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 12:44:48 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 12:44:48 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 12:44:48 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 12:44:48 INFO - Downloading functools32-3.2.3-2.tar.gz 12:44:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:44:48 INFO - warning: no files found matching '*.txt' 12:44:48 INFO - no previously-included directories found matching 'build' 12:44:48 INFO - no previously-included directories found matching 'dist' 12:44:48 INFO - no previously-included directories found matching '.git*' 12:44:48 INFO - Installing collected packages: jsonschema, functools32 12:44:48 INFO - Running setup.py install for functools32 12:44:49 INFO - warning: no files found matching '*.txt' 12:44:49 INFO - no previously-included directories found matching 'build' 12:44:49 INFO - no previously-included directories found matching 'dist' 12:44:49 INFO - no previously-included directories found matching '.git*' 12:44:49 INFO - Successfully installed jsonschema functools32 12:44:49 INFO - Cleaning up... 12:44:49 INFO - Return code: 0 12:44:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:44:49 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')]} 12:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:49 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')]} 12:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:49 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 0x219b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a3b0>, '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 0x245b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245b740>, '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': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', '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 12:44:49 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 12:44:49 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 12:44:49 INFO - Using env: {'DISPLAY': ':0', 12:44:49 INFO - 'HOME': '/home/cltbld', 12:44:49 INFO - 'LANG': 'en_US.UTF-8', 12:44:49 INFO - 'LANGUAGE': 'en_US:en', 12:44:49 INFO - 'LOGNAME': 'cltbld', 12:44:49 INFO - 'MAIL': '/var/mail/cltbld', 12:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:49 INFO - 'MOZ_NO_REMOTE': '1', 12:44:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:49 INFO - 'NO_EM_RESTART': '1', 12:44:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:49 INFO - 'PWD': '/builds/slave/test', 12:44:49 INFO - 'SHELL': '/bin/bash', 12:44:49 INFO - 'SHLVL': '1', 12:44:49 INFO - 'TERM': 'linux', 12:44:49 INFO - 'TMOUT': '86400', 12:44:49 INFO - 'USER': 'cltbld', 12:44:49 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', 12:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:49 INFO - '_': '/tools/buildbot/bin/python'} 12:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:44:49 INFO - Cleaning up... 12:44:49 INFO - Return code: 0 12:44:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:44:49 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')]} 12:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:49 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')]} 12:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:49 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 0x219b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a3b0>, '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 0x245b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245b740>, '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': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', '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 12:44:49 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 12:44:49 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 12:44:49 INFO - Using env: {'DISPLAY': ':0', 12:44:49 INFO - 'HOME': '/home/cltbld', 12:44:49 INFO - 'LANG': 'en_US.UTF-8', 12:44:49 INFO - 'LANGUAGE': 'en_US:en', 12:44:49 INFO - 'LOGNAME': 'cltbld', 12:44:49 INFO - 'MAIL': '/var/mail/cltbld', 12:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:49 INFO - 'MOZ_NO_REMOTE': '1', 12:44:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:49 INFO - 'NO_EM_RESTART': '1', 12:44:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:49 INFO - 'PWD': '/builds/slave/test', 12:44:49 INFO - 'SHELL': '/bin/bash', 12:44:49 INFO - 'SHLVL': '1', 12:44:49 INFO - 'TERM': 'linux', 12:44:49 INFO - 'TMOUT': '86400', 12:44:49 INFO - 'USER': 'cltbld', 12:44:49 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', 12:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:49 INFO - '_': '/tools/buildbot/bin/python'} 12:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:49 INFO - Downloading/unpacking blobuploader==1.2.4 12:44:49 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 12:44:49 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 12:44:49 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 12:44:49 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 12:44:49 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 12:44:49 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 12:44:51 INFO - Downloading blobuploader-1.2.4.tar.gz 12:44:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:44:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:44:51 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 12:44:51 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 12:44:51 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 12:44:51 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 12:44:51 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 12:44:51 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 12:44:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:44:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:44:51 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 12:44:51 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 12:44:51 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 12:44:51 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 12:44:51 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 12:44:51 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 12:44:52 INFO - Downloading docopt-0.6.1.tar.gz 12:44:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:44:52 INFO - Installing collected packages: blobuploader, requests, docopt 12:44:52 INFO - Running setup.py install for blobuploader 12:44:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:44:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:44:52 INFO - Running setup.py install for requests 12:44:52 INFO - Running setup.py install for docopt 12:44:52 INFO - Successfully installed blobuploader requests docopt 12:44:52 INFO - Cleaning up... 12:44:52 INFO - Return code: 0 12:44:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:44:52 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')]} 12:44:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:52 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')]} 12:44:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:52 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 0x219b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a3b0>, '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 0x245b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245b740>, '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': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', '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 12:44:52 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 12:44:52 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 12:44:52 INFO - Using env: {'DISPLAY': ':0', 12:44:52 INFO - 'HOME': '/home/cltbld', 12:44:52 INFO - 'LANG': 'en_US.UTF-8', 12:44:52 INFO - 'LANGUAGE': 'en_US:en', 12:44:52 INFO - 'LOGNAME': 'cltbld', 12:44:52 INFO - 'MAIL': '/var/mail/cltbld', 12:44:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:52 INFO - 'MOZ_NO_REMOTE': '1', 12:44:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:52 INFO - 'NO_EM_RESTART': '1', 12:44:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:52 INFO - 'PWD': '/builds/slave/test', 12:44:52 INFO - 'SHELL': '/bin/bash', 12:44:52 INFO - 'SHLVL': '1', 12:44:52 INFO - 'TERM': 'linux', 12:44:52 INFO - 'TMOUT': '86400', 12:44:52 INFO - 'USER': 'cltbld', 12:44:52 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', 12:44:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:52 INFO - '_': '/tools/buildbot/bin/python'} 12:44:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:44:52 INFO - Running setup.py (path:/tmp/pip-81myGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:44:53 INFO - Running setup.py (path:/tmp/pip-Xx9pZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:44:53 INFO - Running setup.py (path:/tmp/pip-JIjbhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:44:53 INFO - Running setup.py (path:/tmp/pip-c1g3Du-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:44:53 INFO - Running setup.py (path:/tmp/pip-2aCj8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:44:53 INFO - Running setup.py (path:/tmp/pip-vzjmTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:44:53 INFO - Running setup.py (path:/tmp/pip-bQXXip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:44:53 INFO - Running setup.py (path:/tmp/pip-7ndwUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:44:53 INFO - Running setup.py (path:/tmp/pip-QodNku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:44:53 INFO - Running setup.py (path:/tmp/pip-qguns1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:44:53 INFO - Running setup.py (path:/tmp/pip-lrXuj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:44:53 INFO - Running setup.py (path:/tmp/pip-A9HtWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:44:53 INFO - Running setup.py (path:/tmp/pip-aAHCSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:44:53 INFO - Running setup.py (path:/tmp/pip-d8PjU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:44:53 INFO - Running setup.py (path:/tmp/pip-3tj_96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:44:54 INFO - Running setup.py (path:/tmp/pip-aiWc0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:44:54 INFO - Running setup.py (path:/tmp/pip-AZ19a5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:44:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:44:54 INFO - Running setup.py install for manifestparser 12:44:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:44:54 INFO - Running setup.py install for mozcrash 12:44:54 INFO - Running setup.py install for mozdebug 12:44:54 INFO - Running setup.py install for mozdevice 12:44:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:44:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:44:54 INFO - Running setup.py install for mozfile 12:44:54 INFO - Running setup.py install for mozhttpd 12:44:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:44:54 INFO - Running setup.py install for mozinfo 12:44:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:44:54 INFO - Running setup.py install for mozInstall 12:44:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:44:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:44:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:44:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:44:54 INFO - Running setup.py install for mozleak 12:44:55 INFO - Running setup.py install for mozlog 12:44:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:44:55 INFO - Running setup.py install for moznetwork 12:44:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:44:55 INFO - Running setup.py install for mozprocess 12:44:55 INFO - Running setup.py install for mozprofile 12:44:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:44:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:44:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:44:55 INFO - Running setup.py install for mozrunner 12:44:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:44:55 INFO - Running setup.py install for mozscreenshot 12:44:55 INFO - Running setup.py install for moztest 12:44:55 INFO - Running setup.py install for mozversion 12:44:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:44:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:44:55 INFO - Cleaning up... 12:44:55 INFO - Return code: 0 12:44:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:44: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')]} 12:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44: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')]} 12:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:55 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 0x219b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a3b0>, '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 0x245b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245b740>, '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': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', '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 12:44:55 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 12:44:55 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 12:44:55 INFO - Using env: {'DISPLAY': ':0', 12:44:55 INFO - 'HOME': '/home/cltbld', 12:44:55 INFO - 'LANG': 'en_US.UTF-8', 12:44:55 INFO - 'LANGUAGE': 'en_US:en', 12:44:55 INFO - 'LOGNAME': 'cltbld', 12:44:55 INFO - 'MAIL': '/var/mail/cltbld', 12:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:55 INFO - 'MOZ_NO_REMOTE': '1', 12:44:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:55 INFO - 'NO_EM_RESTART': '1', 12:44:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:55 INFO - 'PWD': '/builds/slave/test', 12:44:55 INFO - 'SHELL': '/bin/bash', 12:44:55 INFO - 'SHLVL': '1', 12:44:55 INFO - 'TERM': 'linux', 12:44:55 INFO - 'TMOUT': '86400', 12:44:55 INFO - 'USER': 'cltbld', 12:44:55 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', 12:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:55 INFO - '_': '/tools/buildbot/bin/python'} 12:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:44:56 INFO - Running setup.py (path:/tmp/pip-xlJMjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:44:56 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)) 12:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:44:56 INFO - Running setup.py (path:/tmp/pip-BBaej1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:44:56 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)) 12:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:44:56 INFO - Running setup.py (path:/tmp/pip-feFFon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:44:56 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)) 12:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:44:56 INFO - Running setup.py (path:/tmp/pip-tw7Pyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:44:56 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)) 12:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:44:56 INFO - Running setup.py (path:/tmp/pip-1CNicA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:44:56 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)) 12:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:44:56 INFO - Running setup.py (path:/tmp/pip-FhgRpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:44:56 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)) 12:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:44:56 INFO - Running setup.py (path:/tmp/pip-hOr6Zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:44:56 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)) 12:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:44:56 INFO - Running setup.py (path:/tmp/pip-oXFRPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:44:56 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)) 12:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:44:56 INFO - Running setup.py (path:/tmp/pip-d4IQbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:44:56 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)) 12:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:44:56 INFO - Running setup.py (path:/tmp/pip-UwFB4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:44:56 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)) 12:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:44:56 INFO - Running setup.py (path:/tmp/pip-QQlCpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:44:57 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)) 12:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:44:57 INFO - Running setup.py (path:/tmp/pip-Kz1UOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:44:57 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)) 12:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:44:57 INFO - Running setup.py (path:/tmp/pip-y3vpkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:44:57 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)) 12:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:44:57 INFO - Running setup.py (path:/tmp/pip-qZGuye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:44:57 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)) 12:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:44:57 INFO - Running setup.py (path:/tmp/pip-8sqxGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:44:57 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)) 12:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:44:57 INFO - Running setup.py (path:/tmp/pip-OAv5ZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:44:57 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)) 12:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:44:57 INFO - Running setup.py (path:/tmp/pip-LxibtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:44:57 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)) 12:44:57 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)) 12:44:57 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)) 12:44:57 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)) 12:44:57 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)) 12:44:57 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)) 12:44:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:44:57 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 12:44:57 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 12:44:57 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 12:44:57 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 12:44:57 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 12:44:57 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 12:44:59 INFO - Downloading blessings-1.6.tar.gz 12:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:44:59 INFO - Installing collected packages: blessings 12:44:59 INFO - Running setup.py install for blessings 12:44:59 INFO - Successfully installed blessings 12:44:59 INFO - Cleaning up... 12:44:59 INFO - Return code: 0 12:44:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:44:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:44:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:44:59 INFO - Reading from file tmpfile_stdout 12:44:59 INFO - Current package versions: 12:44:59 INFO - argparse == 1.2.1 12:44:59 INFO - blessings == 1.6 12:44:59 INFO - blobuploader == 1.2.4 12:44:59 INFO - docopt == 0.6.1 12:44:59 INFO - functools32 == 3.2.3-2 12:44:59 INFO - jsonschema == 2.5.1 12:44:59 INFO - manifestparser == 1.1 12:44:59 INFO - mozInstall == 1.12 12:44:59 INFO - mozcrash == 0.17 12:44:59 INFO - mozdebug == 0.1 12:44:59 INFO - mozdevice == 0.48 12:44:59 INFO - mozfile == 1.2 12:44:59 INFO - mozhttpd == 0.7 12:44:59 INFO - mozinfo == 0.9 12:44:59 INFO - mozleak == 0.1 12:44:59 INFO - mozlog == 3.3 12:44:59 INFO - moznetwork == 0.27 12:44:59 INFO - mozprocess == 0.23 12:44:59 INFO - mozprofile == 0.28 12:44:59 INFO - mozrunner == 6.12 12:44:59 INFO - mozscreenshot == 0.1 12:44:59 INFO - mozsystemmonitor == 0.3 12:44:59 INFO - moztest == 0.7 12:44:59 INFO - mozversion == 1.4 12:44:59 INFO - psutil == 3.1.1 12:44:59 INFO - requests == 1.2.3 12:44:59 INFO - wsgiref == 0.1.2 12:44:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:44: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')]} 12:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44: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')]} 12:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:59 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 0x219b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a3b0>, '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 0x245b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245b740>, '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': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', '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 12:44:59 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 12:44:59 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 12:44:59 INFO - Using env: {'DISPLAY': ':0', 12:44:59 INFO - 'HOME': '/home/cltbld', 12:44:59 INFO - 'LANG': 'en_US.UTF-8', 12:44:59 INFO - 'LANGUAGE': 'en_US:en', 12:44:59 INFO - 'LOGNAME': 'cltbld', 12:44:59 INFO - 'MAIL': '/var/mail/cltbld', 12:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:59 INFO - 'MOZ_NO_REMOTE': '1', 12:44:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:59 INFO - 'NO_EM_RESTART': '1', 12:44:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:59 INFO - 'PWD': '/builds/slave/test', 12:44:59 INFO - 'SHELL': '/bin/bash', 12:44:59 INFO - 'SHLVL': '1', 12:44:59 INFO - 'TERM': 'linux', 12:44:59 INFO - 'TMOUT': '86400', 12:44:59 INFO - 'USER': 'cltbld', 12:44:59 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', 12:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:59 INFO - '_': '/tools/buildbot/bin/python'} 12:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:59 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)) 12:44:59 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)) 12:44:59 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)) 12:44:59 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)) 12:44:59 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)) 12:44:59 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)) 12:44:59 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)) 12:44:59 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)) 12:44:59 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)) 12:44:59 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)) 12:44:59 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)) 12:44:59 INFO - Cleaning up... 12:44:59 INFO - Return code: 0 12:44:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:44: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')]} 12:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44: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')]} 12:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:59 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 0x219b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a3b0>, '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 0x245b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245b740>, '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': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', '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 12:44:59 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 12:44:59 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 12:45:00 INFO - Using env: {'DISPLAY': ':0', 12:45:00 INFO - 'HOME': '/home/cltbld', 12:45:00 INFO - 'LANG': 'en_US.UTF-8', 12:45:00 INFO - 'LANGUAGE': 'en_US:en', 12:45:00 INFO - 'LOGNAME': 'cltbld', 12:45:00 INFO - 'MAIL': '/var/mail/cltbld', 12:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:45:00 INFO - 'MOZ_NO_REMOTE': '1', 12:45:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:45:00 INFO - 'NO_EM_RESTART': '1', 12:45:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:45:00 INFO - 'PWD': '/builds/slave/test', 12:45:00 INFO - 'SHELL': '/bin/bash', 12:45:00 INFO - 'SHLVL': '1', 12:45:00 INFO - 'TERM': 'linux', 12:45:00 INFO - 'TMOUT': '86400', 12:45:00 INFO - 'USER': 'cltbld', 12:45:00 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', 12:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:45:00 INFO - '_': '/tools/buildbot/bin/python'} 12:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:45:00 INFO - Cleaning up... 12:45:00 INFO - Return code: 0 12:45:00 INFO - Running post-action listener: _resource_record_post_action 12:45:00 INFO - Running post-action listener: _start_resource_monitoring 12:45:00 INFO - Starting resource monitoring. 12:45:00 INFO - [mozharness: 2016-08-30 19:45:00.241140Z] Finished create-virtualenv step (success) 12:45:00 INFO - [mozharness: 2016-08-30 19:45:00.241720Z] Running install step. 12:45:00 INFO - Running pre-action listener: _resource_record_pre_action 12:45:00 INFO - Running main action method: install 12:45:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:45:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:45:00 INFO - Reading from file tmpfile_stdout 12:45:00 INFO - Detecting whether we're running mozinstall >=1.0... 12:45:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:45:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:45:00 INFO - Reading from file tmpfile_stdout 12:45:00 INFO - Output received: 12:45:00 INFO - Usage: mozinstall [options] installer 12:45:00 INFO - Options: 12:45:00 INFO - -h, --help show this help message and exit 12:45:00 INFO - -d DEST, --destination=DEST 12:45:00 INFO - Directory to install application into. [default: 12:45:00 INFO - "/builds/slave/test"] 12:45:00 INFO - --app=APP Application being installed. [default: firefox] 12:45:00 INFO - mkdir: /builds/slave/test/build/application 12:45:00 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'] 12:45:00 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 12:45:16 INFO - Reading from file tmpfile_stdout 12:45:16 INFO - Output received: 12:45:16 INFO - /builds/slave/test/build/application/firefox/firefox 12:45:16 INFO - Running post-action listener: _resource_record_post_action 12:45:16 INFO - [mozharness: 2016-08-30 19:45:16.677970Z] Finished install step (success) 12:45:16 INFO - [mozharness: 2016-08-30 19:45:16.678247Z] Running run-tests step. 12:45:16 INFO - Running pre-action listener: _resource_record_pre_action 12:45:16 INFO - Running main action method: run_tests 12:45:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:45:16 INFO - Python 2.7.3 12:45:16 INFO - Return code: 0 12:45:16 INFO - grabbing minidump binary from tooltool 12:45: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')]} 12:45:16 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 0x245a3b0>, '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 0x245b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245b740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:45:16 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 12:45:16 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 12:45:16 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 12:45:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:45:16 INFO - Return code: 0 12:45:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:45:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:45:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:45:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:45:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:45:16 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/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 12:45:16 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/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-048 --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 12:45:16 INFO - Using env: {'DISPLAY': ':0', 12:45:16 INFO - 'HOME': '/home/cltbld', 12:45:16 INFO - 'LANG': 'en_US.UTF-8', 12:45:16 INFO - 'LANGUAGE': 'en_US:en', 12:45:16 INFO - 'LOGNAME': 'cltbld', 12:45:16 INFO - 'MAIL': '/var/mail/cltbld', 12:45:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:45:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:45:16 INFO - 'MOZ_NO_REMOTE': '1', 12:45:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:45:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:45:16 INFO - 'NO_EM_RESTART': '1', 12:45:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:45:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:45:16 INFO - 'PWD': '/builds/slave/test', 12:45:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:45:16 INFO - 'SHELL': '/bin/bash', 12:45:16 INFO - 'SHLVL': '1', 12:45:16 INFO - 'TERM': 'linux', 12:45:16 INFO - 'TMOUT': '86400', 12:45:16 INFO - 'USER': 'cltbld', 12:45:16 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751', 12:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:45:16 INFO - '_': '/tools/buildbot/bin/python'} 12:45:16 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/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 12:45:16 INFO - mozversion application_buildid: 20160830093921 12:45:16 INFO - mozversion application_changeset: c819e284a13937fc955c2efb6da7fdf9c5bb8b88 12:45:16 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:45:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:45:16 INFO - mozversion application_name: Firefox 12:45:16 INFO - mozversion application_remotingname: firefox-dev 12:45:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:45:16 INFO - mozversion application_vendor: Mozilla 12:45:16 INFO - mozversion application_version: 50.0a2 12:45:16 INFO - mozversion platform_buildid: 20160830093921 12:45:16 INFO - mozversion platform_changeset: c819e284a13937fc955c2efb6da7fdf9c5bb8b88 12:45:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:45:16 INFO - mozversion platform_version: 50.0a2 12:45:16 INFO - using testdate: 1472586316 12:45:16 INFO - actual date: 1472586316 12:45:16 INFO - starting webserver on 'localhost:43806' 12:45:16 INFO - SUITE-START | Running 2 tests 12:45:16 INFO - TEST-START | dromaeo_css 12:45:16 INFO - operating with platform_type : linux_ 12:45:16 INFO - Initialising browser for dromaeo_css test... 12:45:16 INFO - TEST-INFO | started process 7110 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdmDeYO/profile http://localhost:43806/getInfo.html) 12:45:21 INFO - PROCESS | 7110 | __metrics Screen width/height:1600/1200 12:45:21 INFO - PROCESS | 7110 | colorDepth:24 12:45:21 INFO - PROCESS | 7110 | Browser inner width/height: 1024/697 12:45:21 INFO - PROCESS | 7110 | __metrics 12:45:22 INFO - PROCESS | 7110 | 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" 12:45:22 INFO - PROCESS | 7110 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:45:22 INFO - PROCESS | 7110 | console.error: PushService: 12:45:22 INFO - PROCESS | 7110 | stateChangeProcessEnqueue: Error transitioning state 12:45:22 INFO - PROCESS | 7110 | UnknownError 12:45:22 INFO - TEST-INFO | 7110: exit 0 12:45:22 INFO - Browser initialized. 12:45:22 INFO - Running cycle 1/1 for dromaeo_css test... 12:45:22 INFO - TEST-INFO | started process 7198 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdmDeYO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:45:34 INFO - PROCESS | 7198 | RSS: Main: 145489920 12:45:34 INFO - PROCESS | 7198 | 12:46:25 INFO - PROCESS | 7198 | 1472586385718 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] 12:48:34 INFO - PROCESS | 7198 | Cycle 1(1): loaded http://localhost:43806/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43806/tests/dromaeo/cssquery-ext.html) 12:48:35 INFO - PROCESS | 7198 | RSS: Main: 273375232 12:48:35 INFO - PROCESS | 7198 | 12:51:38 INFO - PROCESS | 7198 | Cycle 1(1): loaded http://localhost:43806/tests/dromaeo/cssquery-ext.html (next: http://localhost:43806/tests/dromaeo/cssquery-jquery.html) 12:51:38 INFO - PROCESS | 7198 | RSS: Main: 233365504 12:51:38 INFO - PROCESS | 7198 | 12:54:40 INFO - PROCESS | 7198 | Cycle 1(1): loaded http://localhost:43806/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43806/tests/dromaeo/cssquery-mootools.html) 12:54:41 INFO - PROCESS | 7198 | RSS: Main: 174166016 12:54:41 INFO - PROCESS | 7198 | 12:57:43 INFO - PROCESS | 7198 | Cycle 1(1): loaded http://localhost:43806/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43806/tests/dromaeo/cssquery-prototype.html) 12:57:43 INFO - PROCESS | 7198 | RSS: Main: 173490176 12:57:43 INFO - PROCESS | 7198 | 13:00:46 INFO - PROCESS | 7198 | Cycle 1(1): loaded http://localhost:43806/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43806/tests/dromaeo/cssquery-yui.html) 13:00:47 INFO - PROCESS | 7198 | RSS: Main: 176979968 13:00:47 INFO - PROCESS | 7198 | 13:03:49 INFO - PROCESS | 7198 | Cycle 1(1): loaded http://localhost:43806/tests/dromaeo/cssquery-yui.html (next: http://localhost:43806/tests/dromaeo/cssquery-dojo.html) 13:03:50 INFO - PROCESS | 7198 | RSS: Main: 262926336 13:03:50 INFO - PROCESS | 7198 | 13:03:50 INFO - PROCESS | 7198 | __start_tp_report 13:03:50 INFO - PROCESS | 7198 | _x_x_mozilla_page_load 13:03:50 INFO - PROCESS | 7198 | _x_x_mozilla_page_load_details 13:03:50 INFO - PROCESS | 7198 | |i|pagename|runs| 13:03:50 INFO - PROCESS | 7198 | |0;dojo.html;3306.0742991962247;3318.767686261963;3327.9001629951103;3345.1971526833568;3359.059463350263;5696.430356964302;5829.883402331956;5832.066869300913;5838.795642152515;5847.415258474145;5789.189513468118;5821.27234095738;5857.4435428634315;5862.88274234515;5883.294004719429;5124.4875512448825;5224.947750522484;5241.947580524194;5300.628955973083;5328.706921119337;5148.304978827848;5172.2241663750365;5182.740862956837;5257.053730328539;5291.126964050946;5240.8689782755355;5243.606729495284;5257.421683614799;5278.762455689484;5279.234510995903;5340.919886201691;5346.8395948121615;5352.57179425645;5355.2770375999135;5355.277037599952;5118.718470484122;5156.226566015091;5157.071727089123;5242.292290540767;5251.816186433494;5803.245578074823;5818.3308919474375;5826.592138550303;5832.037713777222;5832.766689332421;6242.313345531988;6246.312905580383;6246.3441338659695;6250.87498250037;6256.937430625681;6062.939370606326;6119.87760244797;6122.306673174798;6151.96924015377;6297.559170858041;5819.447152520504;5821;5821.825345239649;5823.980803359437;5841.88316233673;6364.522660800459;6369.585976911486;6375.3624637536805;6380.840478988062;6382.042693595998;5895.852603684856;6030.547708921848;6042.909356359658;6084.421979911901;6107.12066608674;6209.5032397408095;6255.71849266775;6259.6870156492;6262.968685156637;6264.749410023639;6187.319394866561;6234.1583886174785;6243.906341404882;6252.155958945576;6254.186955695731;5987.730552125165;6028.065649824284;6076.149339092531;6078.54410919174;6236.438720515175;4730.550597693214;4731.503192164842;4745.738984355894;4756.429228492603;4756.6908150970075;4209.433802212544;4211.894702632459;4216.114615930627;4239.851605193803;4243.172581346669;5700.173474846207;5817.912731309034;5887.705614719247;5891.204687367236;5891.381404952418;6160.168377269011;6278.0269058295125;6297.055441683784;6300.7479700811455;6300.747970081237;5904.291485021739;5914.142449344821;5921.555883308769;5924.170616113747;5924.437178468039;5243.868903277372;5312.973435132877;5343.278657381282;5400.297961265011;5403.432639572867;6339.334369891188;6494.772682956168;6497.967510162419;6499.38255865692;6527.020946857914;6317.936820631735;6318;6320.051992201023;6320.715567799552;6323.747050117944;6101.237345331833;6355.872882542416;6516;6518.804435866931;6525.608463492205;6107.633541987495;6123.029761399571;6148.16999705052;6156.168917196122;6183.536234782319;6253.437190652863;6299.96850015747;6317.494600432046;6322.36776322364;6324.968375158095;6015;6034.969825150846;6161.907571386343;6183.227096612924;6183.96908015457;5980.5514586405325;6073.119397687399;6082.5438092142385;6097.878042439038;6105;6011.789587364421;6017;6037.215162028908;6041.879162416639;6047.213862197887;6038.365971572922;6083.939160608337;6098.32918378969;6133.355997620186;6133.84665383369;6008;6008.24896887889;6013.098035294566;6039.124326972476;6044.909326360196 13:03:50 INFO - PROCESS | 7198 | |1;ext.html;600.4205941266677;618.5762752514526;620.168973575409;653.2194028136379;654.9716944397298;45913.54086459134;47337.526624733786;47612.5238747612;51777.74111129439;51811.22283165756;49501;49989.50010499894;50023.24965125526;50040.24939625909;50113.24830127551;8666.86999695005;9346.439213647169;9493.05069493052;9494.905050949506;11133.443327833616;11025.503852326665;11452.427378631035;11462.083033357312;11462.14033947453;11471.082313414907;11574.710632234177;11582.305061696283;11673.824892626617;11675.883241167607;11681.59114430995;11511.712207194803;11515.366654834064;11561.190716649839;11562.826557601642;11574.421278936019;11372.886271137266;11411.828822567668;11427.714307142305;11437.19939604228;11438.25651332669;46597.767011164724;46880.7655961718;46918;46979.76510117462;47055;3878.204967981557;3906.433567132778;3932.233214523169;4009.799510024487;4102.938455923164;35635.64364356375;35807.89932755832;35818.64181358179;35828.820855895814;36102.27795444103;4547.636189104864;4624.676272660915;4633.331507749591;4647.88380290492;4668.929966050512;384477;385411.9999999972;385598;386649;386677;68044;68316.31683683199;68427;68444;68466;40379.394309085386;41410.1717965645;41598.792006040385;43771.34342984858;70471;18611;18751.531211719815;18759.437216883514;18761.34335298258;18796.812031879505;12830.935845320713;15077.773833392508;15144.16707081099;15287.312070956834;15352.616184595252;21035;21154.78845211528;21337.89331053335;21420.78579214219;21431.571368572542;7429.62851857405;7441.627918604048;7445.478816482794;7452.888206676904;7880.448368614257;1063.9095676867398;1071.4535586850607;1100.6973082402403;1122.7024838417826;1251.787196176652;35541.28917421637;35870.282594348486;36441.81779091088;36674.44988325177;36683;1169.6607983684805;1220.3227208898968;1227.3679055286534;1235.456399184356;1249.1068885746736;18589.907050464662;18883;19036.71444928327;19085.522861928563;19204.71192932107;608716;613452;614474;614681;615044;66280;66803.33196668068;66807;66969.33030669729;66974.66512667503;1371.6090914089436;1406.7678832992442;1416.1078520531998;1511.0631408526565;1526.625976635709;27476.725232747416;36360;36381.0904727384;38272.42591361188;38608.24819374488;64343.35656643374;65103.67448162729;65115.67442162759;65132.34867651263;65191;411239;414495;415077;415317;416138;27887.860560697067;30457.390852183275;30666.386672266875;31135.377292454476;32066.35867282688;52774.472255278495;53591.73204133954;53781.73109134429;53889.191662125886;55308.170377445174;45296.773516132205;45708.77145614383;45887;46601.53398466107;47546.52453475421;45698.77150614226;46742.53257467518;46818;46894.76552617215;47922;44462;46748.7662561685;47167.05665886594;47792.7610361946;47915.520844791106;37705.62294377021;41440;41577;41818.16363672649;42109.86217579469;30516.237094072825;30820.845895771276;30970.69029309678;31208.53187202239;31449.842750786098 13:03:50 INFO - PROCESS | 7198 | |2;jquery.html;409.4370240918737;411.8702608678264;422.4086279209106;428.1052600065862;435.89320616448975;19563.413097607077;20970.475738106554;20995.58008839826;21227.15091396346;21352;18966.81033189668;19223.134958926847;19359.322423715137;19547.5113122172;19838;5427.2401863739005;5648.209250704905;5653.915191272114;5657.43425657433;5658.773649054033;5530;5544.390117087117;5615.2700148980575;5960.5529585281065;5968.1644569760265;5943.722099748553;5951.702414879239;5953.791617293396;5954.732037058343;5955.612885162451;5516.834494965137;5592.748326325325;5597.412271711452;5622.184783206432;5969.074793407029;5417.431169727161;5422.078246698032;5423.755930983115;5448.727563621803;5453.563714902798;14625.341859616343;14776.113433193936;14882.925585372112;14899;14910.925445372812;2405.2423486601665;2521.1050077222503;2569.1423554296234;2577.2397142842906;2592.831465954726;10467.162626989822;10696.839547406795;10746.731331716692;10981.121510279238;11258.04306633937;2005.4485016620547;2019.555697746508;2045.1001559313854;2058.310465993894;2059.721937538428;87319;87330.56334718349;87434.12565874323;88279.55860220658;91357;11620.535178592761;11663.533458661728;11675.12436567261;11809.940950295193;11828.408579570987;15860.52418427449;15863.68272634564;15873.206339682969;15885.920570397075;15891.841081589268;9011.47678649979;9030.729078127666;9137.81724365509;9215.801945747144;9315.254779617615;8935.642574296955;8938.642454301755;9049.864252036094;9168.129027742354;9199.586018629045;4935.555799978019;5079.6698214615935;5117.795288188431;5240.475952404805;5272.261883336336;2020.464576887126;2087.885166315867;2101.53766171442;2165.2854557995825;2185.6830759539775;952.6808519146164;1007.4308015970953;1008.8234558952154;1056.2025670618634;1101.0365929811414;20597.58804823895;20997.580048398948;21224.575508489743;21262.362129136152;21322.36032919015;1049.4805071489661;1049.8057859296055;1061.6072053340158;1076.494047797534;1102.818035024228;11335;11729;11852.170348075551;11926.463309150935;11971.341576213392;521921;530739;531300;537752;541560;54569.72715136479;73555.63222183855;75030.24969750232;96481.51759241159;99085;1011.1708399112657;1040.557762950743;1069.2782371898936;1079.4494807648;1080.897314755097;17567;17680.29278828884;18041.909790450965;18493.352732654294;18708.25166993305;40974.59025409708;42094.36858447187;42343.36484952667;42740.145197096506;43331.566684332756;60868;60990;62024;62589.37410626018;63089;7963.960180199062;8266.941231744053;8502.234798867885;8688.39181257306;8821.426607270507;13850.16898986064;13870.86129138736;14226;14358.353874075483;14407;11474.196806223817;12459.127861050001;12596.496140154657;12764.61706148817;12784.74430511366;12053.57812476559;12242.510299587917;12259.141860069709;12696.936515317364;12704.237745735285;12498.375081246084;12507.874921251036;12684.302363370103;12823.93588032054;12829.550965716155;8154.469959452616;8276.58617069132;8452.02801677815;8484;8528.403011789114;12286.126161952861;12732.936335318263;12757.744845103232;12832.486700532249;12876.678083047998 13:03:50 INFO - PROCESS | 7198 | |3;mootools.html;1425.2659880161716;1529.1360381384518;1673.8158802531727;1870.4482177757561;1893.3373319338234;8889.822203555934;9546.28402869784;9560.42637441754;9564.330496865223;9573.425594464337;8416.66333346667;8912.598933048012;9300.116488933558;9321.673741419052;9617.807643847118;2455.508898220359;2487.9004839806385;2519.0931264744677;2521.823472356936;2523.873806309686;2478.7769100780924;2582.5996970469605;2582.883770230344;2589.572720501115;2597.415581494168;2522.1928982725535;2525.0404846158435;2566.230130960704;2568.4606232691062;2570.3574106473384;2513.5726926422462;2522.369407648088;2530.4306531030556;2536.6195070739445;2542.0594380079397;2466.8766561672023;2474.072222916406;2476.9009239630395;2502.9249122526353;2546.057958555328;9419;9566.521673916277;9579.808403831954;9580.95209523948;9583;5612.635178713371;5737.3688894221605;5744.109663002199;5745.683987380692;5751.539876809845;3645.6172101929183;3759.0602349412648;3822.235552889433;3827.8851634450734;3830.942535861964;1199.6940780101106;1248.5005997600942;1253.7743206222876;1268.3784945376792;1270.5553056430267;13653.522126725517;13819.101758385672;13855.86144138566;13872.583822485241;13903.79144312836;4985.700857948535;5053.090443720165;5084.796608135707;5128.051310507493;5219.42586315505;11406.714832129263;11416.486258118404;11439.599614013636;11441.885581144248;11444.370559619134;5762.4525670061275;5773.278340207474;5777.45114214149;5787.508061814793;5789.565782566325;4639.072185562901;5124.666896651705;5191.9480805192225;5209;5276.287701160371;4297.226499230169;4349.9130017399475;4431.446069241345;4433.733975961452;4483.977580112079;1337.7859542473157;1361.1084739495645;1367.9658008549666;1374.6769509165338;1406.9226192559508;897.9146981036791;962.0619895601733;980.7204946590188;1011.327467234281;1056.566807608892;6143.56995010345;6357.1735674362035;6438.903416448757;6509.1863517060365;6713.1944166700305;491.8352351962061;492.0871782842847;504.1605726465467;512.7923191107608;514.392523364486;4582.725036497821;4597.103564804898;4663.761513320802;4729.5034021427255;4798.736069516141;11670.299782013106;12185.268883866818;12203.267803931793;12223.1443798935;12225.755484890431;11297.20919535641;11822.172447928562;11826.52693892252;12079.698007549705;12085.214461060003;8789.604467798965;8969.372143949991;8986.415882967585;8993.41542799729;9003.189712925869;6253.530985176221;6366.713497892513;6451.41937225652;6554.14796076507;6614.636195009129;10886.129109671048;11106.666799996014;11142.108631309638;11197.944010279896;11521.423928803693;11231.045361144394;11610.129240306842;11621.535138594361;11638.35989020612;11649.1263155262;9159.821556773553;9205.539723013957;9258.444493330422;9365.157135857806;9366.484843333683;9327.300452466228;9364.20404265645;9377.156055954996;9384.67153649619;9390.201832844285;8264.173582641688;8280.296174825207;8310.83378332442;8542.40203185771;8798.956005219932;8205.138460461805;8209.343252539902;8415;8430.578471076298;8546.273566746648;7801.453898227297;7853.842923141391;7859.292663660298;7862.01724784402;7938.04743430792;5472.5348345391085;5536.584756143386;5538.224648549126;5632.549396048388;5792.247007888947;5606.579506536945;5647.181158732043;5684.857878553069;5754.683492407958;5767.68277744728 13:03:50 INFO - PROCESS | 7198 | |4;prototype.html;353.18943025755885;360.7230404368535;369.10860272442056;371.9789178704455;376.7795839433931;5419.458054194579;5428.348598168221;5491;5499.230107784913;5523.944760552393;5207.6875387476775;5413.972930135354;5453.536449401796;5465.562754979602;5471.343438787341;4272.316429371302;4309.93535096973;4328.870133895973;4345.174416860802;4359.280718681413;4326;4334.739915605074;4337.436133302666;4338.761368124752;4348.543402942693;4296.634786043174;4301.612854843063;4306.375575541545;4315.654747620199;4330.643751092896;4206.915861682764;4259.467566554181;4266.701330906838;4300.61294483498;4317.633001194902;3858.093348063203;3861.2470568239205;3861.3049651062797;3871.6128387161057;3878.631530004645;5298.417174110845;5300.310959575269;5300.462865768961;5303.7082960437165;5308.283381743454;4720.551547602972;4880.364603114314;4923.704577725348;4936.259561065833;4940.802367905279;5648.8870222595315;5653.095504719226;5655.406182350876;5657;5667.206591077212;4217.852375166885;4233.375307309488;4240.600601801398;4240.724352917051;4242.278812601834;5575.191597218381;5660.830175094713;5681.9431805681825;5837.278002989128;5929.377415371367;4275.957240427556;4281.357796330575;4284.764337961442;4298.548652391516;4313.158934007838;4346.673999450054;4350.543192964757;4354.368616550582;4354.891127721832;4357.9128417431475;4619.099275641285;4661.580457758818;4743.241081426955;4766.404199475066;4889.633277504201;5628.859278517988;5643.068893632505;5648.15277708347;5654.558087687613;5658.292713410824;1498.2059508460534;1508.947186848455;1509.622594351412;1512.8714059304862;1513.1526345246698;948.2603569216022;979.4661909259471;991.1426615977127;996.3623281100101;1004.8191325561469;5447.809326673627;5464.590155738336;5464.699441530674;5469.206964990054;5476.835694929158;5774.498630356054;5786.971065144648;5817.243758311477;5820.970895145497;5822.214001109796;5348.1175606025345;5487.847552014122;5520.475554822286;5554.166874968787;5585.944140558624;3080.029790615949;3089.9845500772353;3098.9380212395627;3103.596532450767;3107.896696672694;5718.942810571924;5913.319968203626;5974.446643872624;6057.515398768176;6063.939360606426;5839;5869.4717475427415;5890.2342695449315;5915.497182739429;5940.34656187819;5955.970220148872;5975.731092100866;5994.580379373402;5998.940010599926;6002.729877155539;5630.549556035426;5800.274965629296;5811.186433899173;5821.243238379153;5837.912431313449;5508.44915508462;5744.684042377709;5770.163326317576;5770.653760774367;5800.76796928135;5450.672959622435;5882.23530940975;5886.381929897299;5920;5928.199693041384;4185.811638476337;4199.370094485852;4203.201391735561;4228.851990180329;4237.216115018731;5865.94134058654;5875.237428771437;5896.55293053378;5906.379830117776;5913.58604897653;5490.890182196414;5670.546356291405;5670.603057785916;5769.855753606025;5797.130430435468;5619.572061042679;5627;5627.380988091389;5628.577856660685;5632;5553.6390134641115;5587.664740115606;5604.663720176639;5619.100943848965;5643.71781410936;5489.039418101896;5619.325680918316;5701.771929122789;5712.828615141552;5719.8856022880145;5528.640638358494;5581.609287349846;5583.8883222336135;5584.972075139598;5594.1608758686525 13:03:50 INFO - PROCESS | 7198 | |5;yui.html;750.767262148734;761.4821921093654;816.1960468938099;872.8777971084047;894.8747175395446;16517.75223371649;17246.568835779097;17719.911400443012;17738.846974946635;17998.730019049726;15888;15902.204889755523;15911.363545458196;15913;15913.124778137228;737.9262073792605;870.4516154822453;874.4927941793776;882.8896387951506;891.7592250092471;882.3779235638859;883.6553744039829;887.8002449448887;889.5641135843423;891.9598618062203;931.0689310689311;932.5383934952208;933.0436302789627;933.6032186320821;934.1732566678504;773.3310686256376;900.1673452056821;917.3807679816098;924.4176169013546;927.7077720518049;680.4454369688688;680.6936878404738;682.6962001909152;682.754208484945;682.7985744205471;15575.610609734735;15598.830087743465;15649.608759780982;15684.215789210608;15726.842731572653;15003.474878379313;15231.390744370212;15300.08199508033;15710;15731.606709832346;13604.455821767118;13606.863931360658;13629.522966696217;13973.930130349283;14028.789568156486;4591.22708724819;4593.47175074864;4644.326572646981;4654.767261636905;4660.18446771817;67315.66342168307;69527;70949.64525177341;71617.28382716158;72955.63522182462;11776.4700588472;12237.326947018;12309.569165079358;12349.876501235052;12370;21840.563188736138;22062.88968555147;22137.66793498099;22400.10399584031;23055.193068242865;8937.46375217489;9203.217726493189;9218.86171707425;9333.619497781487;9423.90576094244;4774.928376074362;4797.928031079537;4853.150698627726;4874.658773885794;4920.803167873245;3978.1446988896946;4137.855175068858;4141.337386018283;4142;4149.958500415018;685.9421734795613;695.8886578147473;701.9368256856908;702.9472789540703;716.1978583985968;551.7075949746641;636.0745115856417;647.970841312142;683.480554778362;705.5978092487336;8914.256012474776;9309.20871725911;10295.32179704134;10533.631322903662;10698.83951740709;641;682.5256446769547;725.4522835259345;733.7688628082137;738.8263758016861;4735.692180008288;5059.468755780664;5070.847874563769;5102.209157580581;5112.667676601009;128939;130335;132623.3368833169;132666;135486;67883;97492;106214.46892765487;107382;110211;60949.69525152346;83271.5836420814;83574.58212708897;84955.57522212349;94372.52813736025;10620.578490194335;10823.621173258898;11073.501692423699;11145.721356965816;11423.143264255381;56004;56144.157837631654;56293.155602665174;57205.713971429876;57320.42679573151;80677;81380;83250.16749832424;83317;83367;14408.711825763636;14415.711685766435;14430.062045966979;14450.205238711551;14550.27248637585;15494.84505154934;15575.766363504783;15648.2958266876;15816.130112843904;16543;15342.079475231521;15593.76609350838;16224.269907853939;16769.07770072658;16824.66350673004;14534.854651453774;14936.925315373353;15827.841721582638;16007.199640018185;16467.34130634761;13333.066685331934;13614.115082519604;13714.93142534321;13802.930985345009;14132.929335353258;11344.825347033331;11725.648230553097;12115.515379384726;12157;12510.937445312715;11475.368854713071;12755.158115601664;12885.48458061667;12948.15836970594;13134.737305254033 13:03:50 INFO - PROCESS | 7198 | __end_tp_report 13:03:50 INFO - PROCESS | 7198 | __start_cc_report 13:03:50 INFO - PROCESS | 7198 | _x_x_mozilla_cycle_collect,1156 13:03:50 INFO - PROCESS | 7198 | __end_cc_report 13:03:50 INFO - PROCESS | 7198 | __startTimestamp1472587430050__endTimestamp 13:03:50 INFO - PROCESS | 7198 | 13:03:50 INFO - PROCESS | 7198 | ------- Summary: start ------- 13:03:50 INFO - PROCESS | 7198 | Number of tests: 6 13:03:50 INFO - PROCESS | 7198 | 13:03:50 INFO - PROCESS | 7198 | [#0] dojo.html Cycles:165 Average:5774.63 Median:6015.00 stddev:670.30 (11.1%) stddev-sans-first:643.78 13:03:50 INFO - PROCESS | 7198 | Values: 3306.1 3318.8 3327.9 3345.2 3359.1 5696.4 5829.9 5832.1 5838.8 5847.4 5789.2 5821.3 5857.4 5862.9 5883.3 5124.5 5224.9 5241.9 5300.6 5328.7 5148.3 5172.2 5182.7 5257.1 5291.1 5240.9 5243.6 5257.4 5278.8 5279.2 5340.9 5346.8 5352.6 5355.3 5355.3 5118.7 5156.2 5157.1 5242.3 5251.8 5803.2 5818.3 5826.6 5832.0 5832.8 6242.3 6246.3 6246.3 6250.9 6256.9 6062.9 6119.9 6122.3 6152.0 6297.6 5819.4 5821.0 5821.8 5824.0 5841.9 6364.5 6369.6 6375.4 6380.8 6382.0 5895.9 6030.5 6042.9 6084.4 6107.1 6209.5 6255.7 6259.7 6263.0 6264.7 6187.3 6234.2 6243.9 6252.2 6254.2 5987.7 6028.1 6076.1 6078.5 6236.4 4730.6 4731.5 4745.7 4756.4 4756.7 4209.4 4211.9 4216.1 4239.9 4243.2 5700.2 5817.9 5887.7 5891.2 5891.4 6160.2 6278.0 6297.1 6300.7 6300.7 5904.3 5914.1 5921.6 5924.2 5924.4 5243.9 5313.0 5343.3 5400.3 5403.4 6339.3 6494.8 6498.0 6499.4 6527.0 6317.9 6318.0 6320.1 6320.7 6323.7 6101.2 6355.9 6516.0 6518.8 6525.6 6107.6 6123.0 6148.2 6156.2 6183.5 6253.4 6300.0 6317.5 6322.4 6325.0 6015.0 6035.0 6161.9 6183.2 6184.0 5980.6 6073.1 6082.5 6097.9 6105.0 6011.8 6017.0 6037.2 6041.9 6047.2 6038.4 6083.9 6098.3 6133.4 6133.8 6008.0 6008.2 6013.1 6039.1 6044.9 13:03:50 INFO - PROCESS | 7198 | 13:03:50 INFO - PROCESS | 7198 | [#1] ext.html Cycles:180 Average:65562.13 Median:22194.60 stddev:127694.23 (575.3%) stddev-sans-first:127959.29 13:03:50 INFO - PROCESS | 7198 | Values: 600.4 618.6 620.2 653.2 655.0 45913.5 47337.5 47612.5 51777.7 51811.2 49501.0 49989.5 50023.2 50040.2 50113.2 8666.9 9346.4 9493.1 9494.9 11133.4 11025.5 11452.4 11462.1 11462.1 11471.1 11574.7 11582.3 11673.8 11675.9 11681.6 11511.7 11515.4 11561.2 11562.8 11574.4 11372.9 11411.8 11427.7 11437.2 11438.3 46597.8 46880.8 46918.0 46979.8 47055.0 3878.2 3906.4 3932.2 4009.8 4102.9 35635.6 35807.9 35818.6 35828.8 36102.3 4547.6 4624.7 4633.3 4647.9 4668.9 384477.0 385412.0 385598.0 386649.0 386677.0 68044.0 68316.3 68427.0 68444.0 68466.0 40379.4 41410.2 41598.8 43771.3 70471.0 18611.0 18751.5 18759.4 18761.3 18796.8 12830.9 15077.8 15144.2 15287.3 15352.6 21035.0 21154.8 21337.9 21420.8 21431.6 7429.6 7441.6 7445.5 7452.9 7880.4 1063.9 1071.5 1100.7 1122.7 1251.8 35541.3 35870.3 36441.8 36674.4 36683.0 1169.7 1220.3 1227.4 1235.5 1249.1 18589.9 18883.0 19036.7 19085.5 19204.7 608716.0 613452.0 614474.0 614681.0 615044.0 66280.0 66803.3 66807.0 66969.3 66974.7 1371.6 1406.8 1416.1 1511.1 1526.6 27476.7 36360.0 36381.1 38272.4 38608.2 64343.4 65103.7 65115.7 65132.3 65191.0 411239.0 414495.0 415077.0 415317.0 416138.0 27887.9 30457.4 30666.4 31135.4 32066.4 52774.5 53591.7 53781.7 53889.2 55308.2 45296.8 45708.8 45887.0 46601.5 47546.5 45698.8 46742.5 46818.0 46894.8 47922.0 44462.0 46748.8 47167.1 47792.8 47915.5 37705.6 41440.0 41577.0 41818.2 42109.9 30516.2 30820.8 30970.7 31208.5 31449.8 13:03:50 INFO - PROCESS | 7198 | 13:03:50 INFO - PROCESS | 7198 | [#2] jquery.html Cycles:180 Average:30345.84 Median:21337.18 stddev:87559.12 (410.4%) stddev-sans-first:87775.89 13:03:50 INFO - PROCESS | 7198 | Values: 409.4 411.9 422.4 428.1 435.9 19563.4 20970.5 20995.6 21227.2 21352.0 18966.8 19223.1 19359.3 19547.5 19838.0 5427.2 5648.2 5653.9 5657.4 5658.8 5530.0 5544.4 5615.3 5960.6 5968.2 5943.7 5951.7 5953.8 5954.7 5955.6 5516.8 5592.7 5597.4 5622.2 5969.1 5417.4 5422.1 5423.8 5448.7 5453.6 14625.3 14776.1 14882.9 14899.0 14910.9 2405.2 2521.1 2569.1 2577.2 2592.8 10467.2 10696.8 10746.7 10981.1 11258.0 2005.4 2019.6 2045.1 2058.3 2059.7 87319.0 87330.6 87434.1 88279.6 91357.0 11620.5 11663.5 11675.1 11809.9 11828.4 15860.5 15863.7 15873.2 15885.9 15891.8 9011.5 9030.7 9137.8 9215.8 9315.3 8935.6 8938.6 9049.9 9168.1 9199.6 4935.6 5079.7 5117.8 5240.5 5272.3 2020.5 2087.9 2101.5 2165.3 2185.7 952.7 1007.4 1008.8 1056.2 1101.0 20597.6 20997.6 21224.6 21262.4 21322.4 1049.5 1049.8 1061.6 1076.5 1102.8 11335.0 11729.0 11852.2 11926.5 11971.3 521921.0 530739.0 531300.0 537752.0 541560.0 54569.7 73555.6 75030.2 96481.5 99085.0 1011.2 1040.6 1069.3 1079.4 1080.9 17567.0 17680.3 18041.9 18493.4 18708.3 40974.6 42094.4 42343.4 42740.1 43331.6 60868.0 60990.0 62024.0 62589.4 63089.0 7964.0 8266.9 8502.2 8688.4 8821.4 13850.2 13870.9 14226.0 14358.4 14407.0 11474.2 12459.1 12596.5 12764.6 12784.7 12053.6 12242.5 12259.1 12696.9 12704.2 12498.4 12507.9 12684.3 12823.9 12829.6 8154.5 8276.6 8452.0 8484.0 8528.4 12286.1 12732.9 12757.7 12832.5 12876.7 13:03:50 INFO - PROCESS | 7198 | 13:03:50 INFO - PROCESS | 7198 | [#3] mootools.html Cycles:180 Average:6370.89 Median:2744.93 stddev:3680.26 (134.1%) stddev-sans-first:3671.82 13:03:50 INFO - PROCESS | 7198 | Values: 1425.3 1529.1 1673.8 1870.4 1893.3 8889.8 9546.3 9560.4 9564.3 9573.4 8416.7 8912.6 9300.1 9321.7 9617.8 2455.5 2487.9 2519.1 2521.8 2523.9 2478.8 2582.6 2582.9 2589.6 2597.4 2522.2 2525.0 2566.2 2568.5 2570.4 2513.6 2522.4 2530.4 2536.6 2542.1 2466.9 2474.1 2476.9 2502.9 2546.1 9419.0 9566.5 9579.8 9581.0 9583.0 5612.6 5737.4 5744.1 5745.7 5751.5 3645.6 3759.1 3822.2 3827.9 3830.9 1199.7 1248.5 1253.8 1268.4 1270.6 13653.5 13819.1 13855.9 13872.6 13903.8 4985.7 5053.1 5084.8 5128.1 5219.4 11406.7 11416.5 11439.6 11441.9 11444.4 5762.5 5773.3 5777.5 5787.5 5789.6 4639.1 5124.7 5191.9 5209.0 5276.3 4297.2 4349.9 4431.4 4433.7 4484.0 1337.8 1361.1 1368.0 1374.7 1406.9 897.9 962.1 980.7 1011.3 1056.6 6143.6 6357.2 6438.9 6509.2 6713.2 491.8 492.1 504.2 512.8 514.4 4582.7 4597.1 4663.8 4729.5 4798.7 11670.3 12185.3 12203.3 12223.1 12225.8 11297.2 11822.2 11826.5 12079.7 12085.2 8789.6 8969.4 8986.4 8993.4 9003.2 6253.5 6366.7 6451.4 6554.1 6614.6 10886.1 11106.7 11142.1 11197.9 11521.4 11231.0 11610.1 11621.5 11638.4 11649.1 9159.8 9205.5 9258.4 9365.2 9366.5 9327.3 9364.2 9377.2 9384.7 9390.2 8264.2 8280.3 8310.8 8542.4 8799.0 8205.1 8209.3 8415.0 8430.6 8546.3 7801.5 7853.8 7859.3 7862.0 7938.0 5472.5 5536.6 5538.2 5632.5 5792.2 5606.6 5647.2 5684.9 5754.7 5767.7 13:03:50 INFO - PROCESS | 7198 | 13:03:50 INFO - PROCESS | 7198 | [#4] prototype.html Cycles:180 Average:4803.08 Median:5488.44 stddev:1375.74 (25.1%) stddev-sans-first:1338.44 13:03:50 INFO - PROCESS | 7198 | Values: 353.2 360.7 369.1 372.0 376.8 5419.5 5428.3 5491.0 5499.2 5523.9 5207.7 5414.0 5453.5 5465.6 5471.3 4272.3 4309.9 4328.9 4345.2 4359.3 4326.0 4334.7 4337.4 4338.8 4348.5 4296.6 4301.6 4306.4 4315.7 4330.6 4206.9 4259.5 4266.7 4300.6 4317.6 3858.1 3861.2 3861.3 3871.6 3878.6 5298.4 5300.3 5300.5 5303.7 5308.3 4720.6 4880.4 4923.7 4936.3 4940.8 5648.9 5653.1 5655.4 5657.0 5667.2 4217.9 4233.4 4240.6 4240.7 4242.3 5575.2 5660.8 5681.9 5837.3 5929.4 4276.0 4281.4 4284.8 4298.5 4313.2 4346.7 4350.5 4354.4 4354.9 4357.9 4619.1 4661.6 4743.2 4766.4 4889.6 5628.9 5643.1 5648.2 5654.6 5658.3 1498.2 1508.9 1509.6 1512.9 1513.2 948.3 979.5 991.1 996.4 1004.8 5447.8 5464.6 5464.7 5469.2 5476.8 5774.5 5787.0 5817.2 5821.0 5822.2 5348.1 5487.8 5520.5 5554.2 5585.9 3080.0 3090.0 3098.9 3103.6 3107.9 5718.9 5913.3 5974.4 6057.5 6063.9 5839.0 5869.5 5890.2 5915.5 5940.3 5956.0 5975.7 5994.6 5998.9 6002.7 5630.5 5800.3 5811.2 5821.2 5837.9 5508.4 5744.7 5770.2 5770.7 5800.8 5450.7 5882.2 5886.4 5920.0 5928.2 4185.8 4199.4 4203.2 4228.9 4237.2 5865.9 5875.2 5896.6 5906.4 5913.6 5490.9 5670.5 5670.6 5769.9 5797.1 5619.6 5627.0 5627.4 5628.6 5632.0 5553.6 5587.7 5604.7 5619.1 5643.7 5489.0 5619.3 5701.8 5712.8 5719.9 5528.6 5581.6 5583.9 5585.0 5594.2 13:03:50 INFO - PROCESS | 7198 | 13:03:50 INFO - PROCESS | 7198 | [#5] yui.html Cycles:180 Average:21933.56 Median:4370.59 stddev:31433.01 (719.2%) stddev-sans-first:31480.95 13:03:50 INFO - PROCESS | 7198 | Values: 750.8 761.5 816.2 872.9 894.9 16517.8 17246.6 17719.9 17738.8 17998.7 15888.0 15902.2 15911.4 15913.0 15913.1 737.9 870.5 874.5 882.9 891.8 882.4 883.7 887.8 889.6 892.0 931.1 932.5 933.0 933.6 934.2 773.3 900.2 917.4 924.4 927.7 680.4 680.7 682.7 682.8 682.8 15575.6 15598.8 15649.6 15684.2 15726.8 15003.5 15231.4 15300.1 15710.0 15731.6 13604.5 13606.9 13629.5 13973.9 14028.8 4591.2 4593.5 4644.3 4654.8 4660.2 67315.7 69527.0 70949.6 71617.3 72955.6 11776.5 12237.3 12309.6 12349.9 12370.0 21840.6 22062.9 22137.7 22400.1 23055.2 8937.5 9203.2 9218.9 9333.6 9423.9 4774.9 4797.9 4853.2 4874.7 4920.8 3978.1 4137.9 4141.3 4142.0 4150.0 685.9 695.9 701.9 702.9 716.2 551.7 636.1 648.0 683.5 705.6 8914.3 9309.2 10295.3 10533.6 10698.8 641.0 682.5 725.5 733.8 738.8 4735.7 5059.5 5070.8 5102.2 5112.7 128939.0 130335.0 132623.3 132666.0 135486.0 67883.0 97492.0 106214.5 107382.0 110211.0 60949.7 83271.6 83574.6 84955.6 94372.5 10620.6 10823.6 11073.5 11145.7 11423.1 56004.0 56144.2 56293.2 57205.7 57320.4 80677.0 81380.0 83250.2 83317.0 83367.0 14408.7 14415.7 14430.1 14450.2 14550.3 15494.8 15575.8 15648.3 15816.1 16543.0 15342.1 15593.8 16224.3 16769.1 16824.7 14534.9 14936.9 15827.8 16007.2 16467.3 13333.1 13614.1 13714.9 13802.9 14132.9 11344.8 11725.6 12115.5 12157.0 12510.9 11475.4 12755.2 12885.5 12948.2 13134.7 13:03:50 INFO - PROCESS | 7198 | -------- Summary: end -------- 13:03:50 INFO - PROCESS | 7198 | 13:03:50 INFO - TEST-INFO | 7198: exit 0 13:03:50 INFO - TEST-OK | dromaeo_css | took 1113869ms 13:03:50 INFO - TEST-START | kraken 13:03:50 INFO - operating with platform_type : linux_ 13:03:50 INFO - Initialising browser for kraken test... 13:03:50 INFO - TEST-INFO | started process 7399 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCr6g62/profile http://localhost:43806/getInfo.html) 13:03:54 INFO - PROCESS | 7399 | __metrics Screen width/height:1600/1200 13:03:54 INFO - PROCESS | 7399 | colorDepth:24 13:03:54 INFO - PROCESS | 7399 | Browser inner width/height: 1024/697 13:03:54 INFO - PROCESS | 7399 | __metrics 13:03:55 INFO - PROCESS | 7399 | 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" 13:03:55 INFO - PROCESS | 7399 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:03:56 INFO - TEST-INFO | 7399: exit 0 13:03:56 INFO - Browser initialized. 13:03:56 INFO - Running cycle 1/1 for kraken test... 13:03:56 INFO - TEST-INFO | started process 7487 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCr6g62/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:04:08 INFO - PROCESS | 7487 | RSS: Main: 145403904 13:04:08 INFO - PROCESS | 7487 | 13:04:11 INFO - PROCESS | 7487 | Cycle 1(1): loaded http://localhost:43806/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43806/tests/kraken/driver.html?testName=audio-beat-detection) 13:04:11 INFO - PROCESS | 7487 | RSS: Main: 191959040 13:04:11 INFO - PROCESS | 7487 | 13:04:14 INFO - PROCESS | 7487 | Cycle 1(1): loaded http://localhost:43806/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43806/tests/kraken/driver.html?testName=audio-dft) 13:04:15 INFO - PROCESS | 7487 | RSS: Main: 182284288 13:04:15 INFO - PROCESS | 7487 | 13:04:17 INFO - PROCESS | 7487 | Cycle 1(1): loaded http://localhost:43806/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43806/tests/kraken/driver.html?testName=audio-fft) 13:04:18 INFO - PROCESS | 7487 | RSS: Main: 269774848 13:04:18 INFO - PROCESS | 7487 | 13:04:20 INFO - PROCESS | 7487 | Cycle 1(1): loaded http://localhost:43806/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43806/tests/kraken/driver.html?testName=audio-oscillator) 13:04:21 INFO - PROCESS | 7487 | RSS: Main: 175230976 13:04:21 INFO - PROCESS | 7487 | 13:04:23 INFO - PROCESS | 7487 | Cycle 1(1): loaded http://localhost:43806/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43806/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:04:23 INFO - PROCESS | 7487 | RSS: Main: 177283072 13:04:23 INFO - PROCESS | 7487 | 13:04:26 INFO - PROCESS | 7487 | Cycle 1(1): loaded http://localhost:43806/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43806/tests/kraken/driver.html?testName=imaging-darkroom) 13:04:27 INFO - PROCESS | 7487 | RSS: Main: 213159936 13:04:27 INFO - PROCESS | 7487 | 13:04:30 INFO - PROCESS | 7487 | Cycle 1(1): loaded http://localhost:43806/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43806/tests/kraken/driver.html?testName=imaging-desaturate) 13:04:31 INFO - PROCESS | 7487 | RSS: Main: 211832832 13:04:31 INFO - PROCESS | 7487 | 13:04:34 INFO - PROCESS | 7487 | Cycle 1(1): loaded http://localhost:43806/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43806/tests/kraken/driver.html?testName=json-parse-financial) 13:04:34 INFO - PROCESS | 7487 | RSS: Main: 211824640 13:04:34 INFO - PROCESS | 7487 | 13:04:36 INFO - PROCESS | 7487 | Cycle 1(1): loaded http://localhost:43806/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43806/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:04:36 INFO - PROCESS | 7487 | RSS: Main: 175919104 13:04:36 INFO - PROCESS | 7487 | 13:04:38 INFO - PROCESS | 7487 | Cycle 1(1): loaded http://localhost:43806/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43806/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:04:39 INFO - PROCESS | 7487 | RSS: Main: 181403648 13:04:39 INFO - PROCESS | 7487 | 13:04:41 INFO - PROCESS | 7487 | Cycle 1(1): loaded http://localhost:43806/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43806/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:04:41 INFO - PROCESS | 7487 | RSS: Main: 186404864 13:04:41 INFO - PROCESS | 7487 | 13:04:44 INFO - PROCESS | 7487 | Cycle 1(1): loaded http://localhost:43806/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43806/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:04:44 INFO - PROCESS | 7487 | RSS: Main: 182161408 13:04:44 INFO - PROCESS | 7487 | 13:04:47 INFO - PROCESS | 7487 | Cycle 1(1): loaded http://localhost:43806/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43806/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:04:48 INFO - PROCESS | 7487 | RSS: Main: 184287232 13:04:48 INFO - PROCESS | 7487 | 13:04:49 INFO - PROCESS | 7487 | Cycle 1(1): loaded http://localhost:43806/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43806/tests/kraken/driver.html?testName=ai-astar) 13:04:50 INFO - PROCESS | 7487 | RSS: Main: 234926080 13:04:50 INFO - PROCESS | 7487 | 13:04:50 INFO - PROCESS | 7487 | __start_tp_report 13:04:50 INFO - PROCESS | 7487 | _x_x_mozilla_page_load 13:04:50 INFO - PROCESS | 7487 | _x_x_mozilla_page_load_details 13:04:50 INFO - PROCESS | 7487 | |i|pagename|runs| 13:04:50 INFO - PROCESS | 7487 | |0;ai-astar;100;109;108;110;108;110;109;108;110;109 13:04:50 INFO - PROCESS | 7487 | |1;audio-beat-detection;145;114;116;179;119;123;152;123;163;115 13:04:50 INFO - PROCESS | 7487 | |2;audio-dft;161;160;161;160;160;160;165;160;160;160 13:04:50 INFO - PROCESS | 7487 | |3;audio-fft;76;76;75;111;77;78;75;73;98;75 13:04:50 INFO - PROCESS | 7487 | |4;audio-oscillator;83;83;137;83;84;83;115;84;84;122 13:04:50 INFO - PROCESS | 7487 | |5;imaging-gaussian-blur;117;116;117;117;116;117;117;117;117;116 13:04:50 INFO - PROCESS | 7487 | |6;imaging-darkroom;145;146;145;146;146;146;146;146;146;145 13:04:50 INFO - PROCESS | 7487 | |7;imaging-desaturate;95;95;95;95;95;95;95;96;96;95 13:04:50 INFO - PROCESS | 7487 | |8;json-parse-financial;50;50;50;50;50;49;51;49;59;49 13:04:50 INFO - PROCESS | 7487 | |9;json-stringify-tinderbox;58;59;59;59;59;58;59;59;60;60 13:04:50 INFO - PROCESS | 7487 | |10;stanford-crypto-aes;72;80;73;77;72;72;72;72;73;72 13:04:50 INFO - PROCESS | 7487 | |11;stanford-crypto-ccm;118;126;119;117;120;115;119;113;167;120 13:04:50 INFO - PROCESS | 7487 | |12;stanford-crypto-pbkdf2;152;158;160;158;157;154;154;154;152;157 13:04:50 INFO - PROCESS | 7487 | |13;stanford-crypto-sha256-iterative;60;61;64;61;61;64;61;62;63;64 13:04:50 INFO - PROCESS | 7487 | __end_tp_report 13:04:50 INFO - PROCESS | 7487 | __start_cc_report 13:04:50 INFO - PROCESS | 7487 | _x_x_mozilla_cycle_collect,1634 13:04:50 INFO - PROCESS | 7487 | __end_cc_report 13:04:50 INFO - PROCESS | 7487 | __startTimestamp1472587490012__endTimestamp 13:04:50 INFO - PROCESS | 7487 | 13:04:50 INFO - PROCESS | 7487 | ------- Summary: start ------- 13:04:50 INFO - PROCESS | 7487 | Number of tests: 14 13:04:50 INFO - PROCESS | 7487 | 13:04:50 INFO - PROCESS | 7487 | [#0] ai-astar Cycles:10 Average:108.10 Median:109.00 stddev:2.96 (2.7%) stddev-sans-first:0.87 13:04:50 INFO - PROCESS | 7487 | Values: 100.0 109.0 108.0 110.0 108.0 110.0 109.0 108.0 110.0 109.0 13:04:50 INFO - PROCESS | 7487 | 13:04:50 INFO - PROCESS | 7487 | [#1] audio-beat-detection Cycles:10 Average:134.90 Median:134.00 stddev:23.23 (17.3%) stddev-sans-first:24.35 13:04:50 INFO - PROCESS | 7487 | Values: 145.0 114.0 116.0 179.0 119.0 123.0 152.0 123.0 163.0 115.0 13:04:50 INFO - PROCESS | 7487 | 13:04:50 INFO - PROCESS | 7487 | [#2] audio-dft Cycles:10 Average:160.70 Median:160.00 stddev:1.57 (1.0%) stddev-sans-first:1.66 13:04:50 INFO - PROCESS | 7487 | Values: 161.0 160.0 161.0 160.0 160.0 160.0 165.0 160.0 160.0 160.0 13:04:50 INFO - PROCESS | 7487 | 13:04:50 INFO - PROCESS | 7487 | [#3] audio-fft Cycles:10 Average:81.40 Median:76.00 stddev:12.62 (16.6%) stddev-sans-first:13.24 13:04:50 INFO - PROCESS | 7487 | Values: 76.0 76.0 75.0 111.0 77.0 78.0 75.0 73.0 98.0 75.0 13:04:50 INFO - PROCESS | 7487 | 13:04:50 INFO - PROCESS | 7487 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:83.00 stddev:20.62 (24.8%) stddev-sans-first:21.34 13:04:50 INFO - PROCESS | 7487 | Values: 83.0 83.0 137.0 83.0 84.0 83.0 115.0 84.0 84.0 122.0 13:04:50 INFO - PROCESS | 7487 | 13:04:50 INFO - PROCESS | 7487 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 13:04:50 INFO - PROCESS | 7487 | Values: 117.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 13:04:50 INFO - PROCESS | 7487 | 13:04:50 INFO - PROCESS | 7487 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 13:04:50 INFO - PROCESS | 7487 | Values: 145.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 13:04:50 INFO - PROCESS | 7487 | 13:04:50 INFO - PROCESS | 7487 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 13:04:50 INFO - PROCESS | 7487 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 13:04:50 INFO - PROCESS | 7487 | 13:04:50 INFO - PROCESS | 7487 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:50.00 stddev:2.98 (6.0%) stddev-sans-first:3.15 13:04:50 INFO - PROCESS | 7487 | Values: 50.0 50.0 50.0 50.0 50.0 49.0 51.0 49.0 59.0 49.0 13:04:50 INFO - PROCESS | 7487 | 13:04:50 INFO - PROCESS | 7487 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:0.67 (1.1%) stddev-sans-first:0.60 13:04:50 INFO - PROCESS | 7487 | Values: 58.0 59.0 59.0 59.0 59.0 58.0 59.0 59.0 60.0 60.0 13:04:50 INFO - PROCESS | 7487 | 13:04:50 INFO - PROCESS | 7487 | [#10] stanford-crypto-aes Cycles:10 Average:73.50 Median:72.50 stddev:2.76 (3.8%) stddev-sans-first:2.87 13:04:50 INFO - PROCESS | 7487 | Values: 72.0 80.0 73.0 77.0 72.0 72.0 72.0 72.0 73.0 72.0 13:04:50 INFO - PROCESS | 7487 | 13:04:50 INFO - PROCESS | 7487 | [#11] stanford-crypto-ccm Cycles:10 Average:123.40 Median:119.50 stddev:15.70 (13.1%) stddev-sans-first:16.53 13:04:50 INFO - PROCESS | 7487 | Values: 118.0 126.0 119.0 117.0 120.0 115.0 119.0 113.0 167.0 120.0 13:04:50 INFO - PROCESS | 7487 | 13:04:50 INFO - PROCESS | 7487 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.60 Median:157.00 stddev:2.76 (1.8%) stddev-sans-first:2.60 13:04:50 INFO - PROCESS | 7487 | Values: 152.0 158.0 160.0 158.0 157.0 154.0 154.0 154.0 152.0 157.0 13:04:50 INFO - PROCESS | 7487 | 13:04:50 INFO - PROCESS | 7487 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.50 stddev:1.52 (2.4%) stddev-sans-first:1.41 13:04:50 INFO - PROCESS | 7487 | Values: 60.0 61.0 64.0 61.0 61.0 64.0 61.0 62.0 63.0 64.0 13:04:50 INFO - PROCESS | 7487 | -------- Summary: end -------- 13:04:50 INFO - PROCESS | 7487 | 13:04:51 INFO - TEST-INFO | 7487: exit 0 13:04:51 INFO - TEST-OK | kraken | took 60248ms 13:04:51 INFO - SUITE-END | took 1174s 13:04:51 INFO - Completed test suite (00:19:34) 13:04:51 INFO - javascript benchmark 13:04:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3306.0742991962247, 3318.767686261963, 3327.9001629951103, 3345.1971526833568, 3359.059463350263, 5696.430356964302, 5829.883402331956, 5832.066869300913, 5838.795642152515, 5847.415258474145, 5789.189513468118, 5821.27234095738, 5857.4435428634315, 5862.88274234515, 5883.294004719429, 5124.4875512448825, 5224.947750522484, 5241.947580524194, 5300.628955973083, 5328.706921119337, 5148.304978827848, 5172.2241663750365, 5182.740862956837, 5257.053730328539, 5291.126964050946, 5240.8689782755355, 5243.606729495284, 5257.421683614799, 5278.762455689484, 5279.234510995903, 5340.919886201691, 5346.8395948121615, 5352.57179425645, 5355.2770375999135, 5355.277037599952, 5118.718470484122, 5156.226566015091, 5157.071727089123, 5242.292290540767, 5251.816186433494, 5803.245578074823, 5818.3308919474375, 5826.592138550303, 5832.037713777222, 5832.766689332421, 6242.313345531988, 6246.312905580383, 6246.3441338659695, 6250.87498250037, 6256.937430625681, 6062.939370606326, 6119.87760244797, 6122.306673174798, 6151.96924015377, 6297.559170858041, 5819.447152520504, 5821.0, 5821.825345239649, 5823.980803359437, 5841.88316233673, 6364.522660800459, 6369.585976911486, 6375.3624637536805, 6380.840478988062, 6382.042693595998, 5895.852603684856, 6030.547708921848, 6042.909356359658, 6084.421979911901, 6107.12066608674, 6209.5032397408095, 6255.71849266775, 6259.6870156492, 6262.968685156637, 6264.749410023639, 6187.319394866561, 6234.1583886174785, 6243.906341404882, 6252.155958945576, 6254.186955695731, 5987.730552125165, 6028.065649824284, 6076.149339092531, 6078.54410919174, 6236.438720515175, 4730.550597693214, 4731.503192164842, 4745.738984355894, 4756.429228492603, 4756.6908150970075, 4209.433802212544, 4211.894702632459, 4216.114615930627, 4239.851605193803, 4243.172581346669, 5700.173474846207, 5817.912731309034, 5887.705614719247, 5891.204687367236, 5891.381404952418, 6160.168377269011, 6278.0269058295125, 6297.055441683784, 6300.7479700811455, 6300.747970081237, 5904.291485021739, 5914.142449344821, 5921.555883308769, 5924.170616113747, 5924.437178468039, 5243.868903277372, 5312.973435132877, 5343.278657381282, 5400.297961265011, 5403.432639572867, 6339.334369891188, 6494.772682956168, 6497.967510162419, 6499.38255865692, 6527.020946857914, 6317.936820631735, 6318.0, 6320.051992201023, 6320.715567799552, 6323.747050117944, 6101.237345331833, 6355.872882542416, 6516.0, 6518.804435866931, 6525.608463492205, 6107.633541987495, 6123.029761399571, 6148.16999705052, 6156.168917196122, 6183.536234782319, 6253.437190652863, 6299.96850015747, 6317.494600432046, 6322.36776322364, 6324.968375158095, 6015.0, 6034.969825150846, 6161.907571386343, 6183.227096612924, 6183.96908015457, 5980.5514586405325, 6073.119397687399, 6082.5438092142385, 6097.878042439038, 6105.0, 6011.789587364421, 6017.0, 6037.215162028908, 6041.879162416639, 6047.213862197887, 6038.365971572922, 6083.939160608337, 6098.32918378969, 6133.355997620186, 6133.84665383369, 6008.0, 6008.24896887889, 6013.098035294566, 6039.124326972476, 6044.909326360196], "value": 5728.286188669387, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.4205941266677, 618.5762752514526, 620.168973575409, 653.2194028136379, 654.9716944397298, 45913.54086459134, 47337.526624733786, 47612.5238747612, 51777.74111129439, 51811.22283165756, 49501.0, 49989.50010499894, 50023.24965125526, 50040.24939625909, 50113.24830127551, 8666.86999695005, 9346.439213647169, 9493.05069493052, 9494.905050949506, 11133.443327833616, 11025.503852326665, 11452.427378631035, 11462.083033357312, 11462.14033947453, 11471.082313414907, 11574.710632234177, 11582.305061696283, 11673.824892626617, 11675.883241167607, 11681.59114430995, 11511.712207194803, 11515.366654834064, 11561.190716649839, 11562.826557601642, 11574.421278936019, 11372.886271137266, 11411.828822567668, 11427.714307142305, 11437.19939604228, 11438.25651332669, 46597.767011164724, 46880.7655961718, 46918.0, 46979.76510117462, 47055.0, 3878.204967981557, 3906.433567132778, 3932.233214523169, 4009.799510024487, 4102.938455923164, 35635.64364356375, 35807.89932755832, 35818.64181358179, 35828.820855895814, 36102.27795444103, 4547.636189104864, 4624.676272660915, 4633.331507749591, 4647.88380290492, 4668.929966050512, 384477.0, 385411.9999999972, 385598.0, 386649.0, 386677.0, 68044.0, 68316.31683683199, 68427.0, 68444.0, 68466.0, 40379.394309085386, 41410.1717965645, 41598.792006040385, 43771.34342984858, 70471.0, 18611.0, 18751.531211719815, 18759.437216883514, 18761.34335298258, 18796.812031879505, 12830.935845320713, 15077.773833392508, 15144.16707081099, 15287.312070956834, 15352.616184595252, 21035.0, 21154.78845211528, 21337.89331053335, 21420.78579214219, 21431.571368572542, 7429.62851857405, 7441.627918604048, 7445.478816482794, 7452.888206676904, 7880.448368614257, 1063.9095676867398, 1071.4535586850607, 1100.6973082402403, 1122.7024838417826, 1251.787196176652, 35541.28917421637, 35870.282594348486, 36441.81779091088, 36674.44988325177, 36683.0, 1169.6607983684805, 1220.3227208898968, 1227.3679055286534, 1235.456399184356, 1249.1068885746736, 18589.907050464662, 18883.0, 19036.71444928327, 19085.522861928563, 19204.71192932107, 608716.0, 613452.0, 614474.0, 614681.0, 615044.0, 66280.0, 66803.33196668068, 66807.0, 66969.33030669729, 66974.66512667503, 1371.6090914089436, 1406.7678832992442, 1416.1078520531998, 1511.0631408526565, 1526.625976635709, 27476.725232747416, 36360.0, 36381.0904727384, 38272.42591361188, 38608.24819374488, 64343.35656643374, 65103.67448162729, 65115.67442162759, 65132.34867651263, 65191.0, 411239.0, 414495.0, 415077.0, 415317.0, 416138.0, 27887.860560697067, 30457.390852183275, 30666.386672266875, 31135.377292454476, 32066.35867282688, 52774.472255278495, 53591.73204133954, 53781.73109134429, 53889.191662125886, 55308.170377445174, 45296.773516132205, 45708.77145614383, 45887.0, 46601.53398466107, 47546.52453475421, 45698.77150614226, 46742.53257467518, 46818.0, 46894.76552617215, 47922.0, 44462.0, 46748.7662561685, 47167.05665886594, 47792.7610361946, 47915.520844791106, 37705.62294377021, 41440.0, 41577.0, 41818.16363672649, 42109.86217579469, 30516.237094072825, 30820.845895771276, 30970.69029309678, 31208.53187202239, 31449.842750786098], "value": 22524.213728862527, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.4370240918737, 411.8702608678264, 422.4086279209106, 428.1052600065862, 435.89320616448975, 19563.413097607077, 20970.475738106554, 20995.58008839826, 21227.15091396346, 21352.0, 18966.81033189668, 19223.134958926847, 19359.322423715137, 19547.5113122172, 19838.0, 5427.2401863739005, 5648.209250704905, 5653.915191272114, 5657.43425657433, 5658.773649054033, 5530.0, 5544.390117087117, 5615.2700148980575, 5960.5529585281065, 5968.1644569760265, 5943.722099748553, 5951.702414879239, 5953.791617293396, 5954.732037058343, 5955.612885162451, 5516.834494965137, 5592.748326325325, 5597.412271711452, 5622.184783206432, 5969.074793407029, 5417.431169727161, 5422.078246698032, 5423.755930983115, 5448.727563621803, 5453.563714902798, 14625.341859616343, 14776.113433193936, 14882.925585372112, 14899.0, 14910.925445372812, 2405.2423486601665, 2521.1050077222503, 2569.1423554296234, 2577.2397142842906, 2592.831465954726, 10467.162626989822, 10696.839547406795, 10746.731331716692, 10981.121510279238, 11258.04306633937, 2005.4485016620547, 2019.555697746508, 2045.1001559313854, 2058.310465993894, 2059.721937538428, 87319.0, 87330.56334718349, 87434.12565874323, 88279.55860220658, 91357.0, 11620.535178592761, 11663.533458661728, 11675.12436567261, 11809.940950295193, 11828.408579570987, 15860.52418427449, 15863.68272634564, 15873.206339682969, 15885.920570397075, 15891.841081589268, 9011.47678649979, 9030.729078127666, 9137.81724365509, 9215.801945747144, 9315.254779617615, 8935.642574296955, 8938.642454301755, 9049.864252036094, 9168.129027742354, 9199.586018629045, 4935.555799978019, 5079.6698214615935, 5117.795288188431, 5240.475952404805, 5272.261883336336, 2020.464576887126, 2087.885166315867, 2101.53766171442, 2165.2854557995825, 2185.6830759539775, 952.6808519146164, 1007.4308015970953, 1008.8234558952154, 1056.2025670618634, 1101.0365929811414, 20597.58804823895, 20997.580048398948, 21224.575508489743, 21262.362129136152, 21322.36032919015, 1049.4805071489661, 1049.8057859296055, 1061.6072053340158, 1076.494047797534, 1102.818035024228, 11335.0, 11729.0, 11852.170348075551, 11926.463309150935, 11971.341576213392, 521921.0, 530739.0, 531300.0, 537752.0, 541560.0, 54569.72715136479, 73555.63222183855, 75030.24969750232, 96481.51759241159, 99085.0, 1011.1708399112657, 1040.557762950743, 1069.2782371898936, 1079.4494807648, 1080.897314755097, 17567.0, 17680.29278828884, 18041.909790450965, 18493.352732654294, 18708.25166993305, 40974.59025409708, 42094.36858447187, 42343.36484952667, 42740.145197096506, 43331.566684332756, 60868.0, 60990.0, 62024.0, 62589.37410626018, 63089.0, 7963.960180199062, 8266.941231744053, 8502.234798867885, 8688.39181257306, 8821.426607270507, 13850.16898986064, 13870.86129138736, 14226.0, 14358.353874075483, 14407.0, 11474.196806223817, 12459.127861050001, 12596.496140154657, 12764.61706148817, 12784.74430511366, 12053.57812476559, 12242.510299587917, 12259.141860069709, 12696.936515317364, 12704.237745735285, 12498.375081246084, 12507.874921251036, 12684.302363370103, 12823.93588032054, 12829.550965716155, 8154.469959452616, 8276.58617069132, 8452.02801677815, 8484.0, 8528.403011789114, 12286.126161952861, 12732.936335318263, 12757.744845103232, 12832.486700532249, 12876.678083047998], "value": 9528.367047884918, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.2659880161716, 1529.1360381384518, 1673.8158802531727, 1870.4482177757561, 1893.3373319338234, 8889.822203555934, 9546.28402869784, 9560.42637441754, 9564.330496865223, 9573.425594464337, 8416.66333346667, 8912.598933048012, 9300.116488933558, 9321.673741419052, 9617.807643847118, 2455.508898220359, 2487.9004839806385, 2519.0931264744677, 2521.823472356936, 2523.873806309686, 2478.7769100780924, 2582.5996970469605, 2582.883770230344, 2589.572720501115, 2597.415581494168, 2522.1928982725535, 2525.0404846158435, 2566.230130960704, 2568.4606232691062, 2570.3574106473384, 2513.5726926422462, 2522.369407648088, 2530.4306531030556, 2536.6195070739445, 2542.0594380079397, 2466.8766561672023, 2474.072222916406, 2476.9009239630395, 2502.9249122526353, 2546.057958555328, 9419.0, 9566.521673916277, 9579.808403831954, 9580.95209523948, 9583.0, 5612.635178713371, 5737.3688894221605, 5744.109663002199, 5745.683987380692, 5751.539876809845, 3645.6172101929183, 3759.0602349412648, 3822.235552889433, 3827.8851634450734, 3830.942535861964, 1199.6940780101106, 1248.5005997600942, 1253.7743206222876, 1268.3784945376792, 1270.5553056430267, 13653.522126725517, 13819.101758385672, 13855.86144138566, 13872.583822485241, 13903.79144312836, 4985.700857948535, 5053.090443720165, 5084.796608135707, 5128.051310507493, 5219.42586315505, 11406.714832129263, 11416.486258118404, 11439.599614013636, 11441.885581144248, 11444.370559619134, 5762.4525670061275, 5773.278340207474, 5777.45114214149, 5787.508061814793, 5789.565782566325, 4639.072185562901, 5124.666896651705, 5191.9480805192225, 5209.0, 5276.287701160371, 4297.226499230169, 4349.9130017399475, 4431.446069241345, 4433.733975961452, 4483.977580112079, 1337.7859542473157, 1361.1084739495645, 1367.9658008549666, 1374.6769509165338, 1406.9226192559508, 897.9146981036791, 962.0619895601733, 980.7204946590188, 1011.327467234281, 1056.566807608892, 6143.56995010345, 6357.1735674362035, 6438.903416448757, 6509.1863517060365, 6713.1944166700305, 491.8352351962061, 492.0871782842847, 504.1605726465467, 512.7923191107608, 514.392523364486, 4582.725036497821, 4597.103564804898, 4663.761513320802, 4729.5034021427255, 4798.736069516141, 11670.299782013106, 12185.268883866818, 12203.267803931793, 12223.1443798935, 12225.755484890431, 11297.20919535641, 11822.172447928562, 11826.52693892252, 12079.698007549705, 12085.214461060003, 8789.604467798965, 8969.372143949991, 8986.415882967585, 8993.41542799729, 9003.189712925869, 6253.530985176221, 6366.713497892513, 6451.41937225652, 6554.14796076507, 6614.636195009129, 10886.129109671048, 11106.666799996014, 11142.108631309638, 11197.944010279896, 11521.423928803693, 11231.045361144394, 11610.129240306842, 11621.535138594361, 11638.35989020612, 11649.1263155262, 9159.821556773553, 9205.539723013957, 9258.444493330422, 9365.157135857806, 9366.484843333683, 9327.300452466228, 9364.20404265645, 9377.156055954996, 9384.67153649619, 9390.201832844285, 8264.173582641688, 8280.296174825207, 8310.83378332442, 8542.40203185771, 8798.956005219932, 8205.138460461805, 8209.343252539902, 8415.0, 8430.578471076298, 8546.273566746648, 7801.453898227297, 7853.842923141391, 7859.292663660298, 7862.01724784402, 7938.04743430792, 5472.5348345391085, 5536.584756143386, 5538.224648549126, 5632.549396048388, 5792.247007888947, 5606.579506536945, 5647.181158732043, 5684.857878553069, 5754.683492407958, 5767.68277744728], "value": 4987.562921171749, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.18943025755885, 360.7230404368535, 369.10860272442056, 371.9789178704455, 376.7795839433931, 5419.458054194579, 5428.348598168221, 5491.0, 5499.230107784913, 5523.944760552393, 5207.6875387476775, 5413.972930135354, 5453.536449401796, 5465.562754979602, 5471.343438787341, 4272.316429371302, 4309.93535096973, 4328.870133895973, 4345.174416860802, 4359.280718681413, 4326.0, 4334.739915605074, 4337.436133302666, 4338.761368124752, 4348.543402942693, 4296.634786043174, 4301.612854843063, 4306.375575541545, 4315.654747620199, 4330.643751092896, 4206.915861682764, 4259.467566554181, 4266.701330906838, 4300.61294483498, 4317.633001194902, 3858.093348063203, 3861.2470568239205, 3861.3049651062797, 3871.6128387161057, 3878.631530004645, 5298.417174110845, 5300.310959575269, 5300.462865768961, 5303.7082960437165, 5308.283381743454, 4720.551547602972, 4880.364603114314, 4923.704577725348, 4936.259561065833, 4940.802367905279, 5648.8870222595315, 5653.095504719226, 5655.406182350876, 5657.0, 5667.206591077212, 4217.852375166885, 4233.375307309488, 4240.600601801398, 4240.724352917051, 4242.278812601834, 5575.191597218381, 5660.830175094713, 5681.9431805681825, 5837.278002989128, 5929.377415371367, 4275.957240427556, 4281.357796330575, 4284.764337961442, 4298.548652391516, 4313.158934007838, 4346.673999450054, 4350.543192964757, 4354.368616550582, 4354.891127721832, 4357.9128417431475, 4619.099275641285, 4661.580457758818, 4743.241081426955, 4766.404199475066, 4889.633277504201, 5628.859278517988, 5643.068893632505, 5648.15277708347, 5654.558087687613, 5658.292713410824, 1498.2059508460534, 1508.947186848455, 1509.622594351412, 1512.8714059304862, 1513.1526345246698, 948.2603569216022, 979.4661909259471, 991.1426615977127, 996.3623281100101, 1004.8191325561469, 5447.809326673627, 5464.590155738336, 5464.699441530674, 5469.206964990054, 5476.835694929158, 5774.498630356054, 5786.971065144648, 5817.243758311477, 5820.970895145497, 5822.214001109796, 5348.1175606025345, 5487.847552014122, 5520.475554822286, 5554.166874968787, 5585.944140558624, 3080.029790615949, 3089.9845500772353, 3098.9380212395627, 3103.596532450767, 3107.896696672694, 5718.942810571924, 5913.319968203626, 5974.446643872624, 6057.515398768176, 6063.939360606426, 5839.0, 5869.4717475427415, 5890.2342695449315, 5915.497182739429, 5940.34656187819, 5955.970220148872, 5975.731092100866, 5994.580379373402, 5998.940010599926, 6002.729877155539, 5630.549556035426, 5800.274965629296, 5811.186433899173, 5821.243238379153, 5837.912431313449, 5508.44915508462, 5744.684042377709, 5770.163326317576, 5770.653760774367, 5800.76796928135, 5450.672959622435, 5882.23530940975, 5886.381929897299, 5920.0, 5928.199693041384, 4185.811638476337, 4199.370094485852, 4203.201391735561, 4228.851990180329, 4237.216115018731, 5865.94134058654, 5875.237428771437, 5896.55293053378, 5906.379830117776, 5913.58604897653, 5490.890182196414, 5670.546356291405, 5670.603057785916, 5769.855753606025, 5797.130430435468, 5619.572061042679, 5627.0, 5627.380988091389, 5628.577856660685, 5632.0, 5553.6390134641115, 5587.664740115606, 5604.663720176639, 5619.100943848965, 5643.71781410936, 5489.039418101896, 5619.325680918316, 5701.771929122789, 5712.828615141552, 5719.8856022880145, 5528.640638358494, 5581.609287349846, 5583.8883222336135, 5584.972075139598, 5594.1608758686525], "value": 4372.266632530858, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [750.767262148734, 761.4821921093654, 816.1960468938099, 872.8777971084047, 894.8747175395446, 16517.75223371649, 17246.568835779097, 17719.911400443012, 17738.846974946635, 17998.730019049726, 15888.0, 15902.204889755523, 15911.363545458196, 15913.0, 15913.124778137228, 737.9262073792605, 870.4516154822453, 874.4927941793776, 882.8896387951506, 891.7592250092471, 882.3779235638859, 883.6553744039829, 887.8002449448887, 889.5641135843423, 891.9598618062203, 931.0689310689311, 932.5383934952208, 933.0436302789627, 933.6032186320821, 934.1732566678504, 773.3310686256376, 900.1673452056821, 917.3807679816098, 924.4176169013546, 927.7077720518049, 680.4454369688688, 680.6936878404738, 682.6962001909152, 682.754208484945, 682.7985744205471, 15575.610609734735, 15598.830087743465, 15649.608759780982, 15684.215789210608, 15726.842731572653, 15003.474878379313, 15231.390744370212, 15300.08199508033, 15710.0, 15731.606709832346, 13604.455821767118, 13606.863931360658, 13629.522966696217, 13973.930130349283, 14028.789568156486, 4591.22708724819, 4593.47175074864, 4644.326572646981, 4654.767261636905, 4660.18446771817, 67315.66342168307, 69527.0, 70949.64525177341, 71617.28382716158, 72955.63522182462, 11776.4700588472, 12237.326947018, 12309.569165079358, 12349.876501235052, 12370.0, 21840.563188736138, 22062.88968555147, 22137.66793498099, 22400.10399584031, 23055.193068242865, 8937.46375217489, 9203.217726493189, 9218.86171707425, 9333.619497781487, 9423.90576094244, 4774.928376074362, 4797.928031079537, 4853.150698627726, 4874.658773885794, 4920.803167873245, 3978.1446988896946, 4137.855175068858, 4141.337386018283, 4142.0, 4149.958500415018, 685.9421734795613, 695.8886578147473, 701.9368256856908, 702.9472789540703, 716.1978583985968, 551.7075949746641, 636.0745115856417, 647.970841312142, 683.480554778362, 705.5978092487336, 8914.256012474776, 9309.20871725911, 10295.32179704134, 10533.631322903662, 10698.83951740709, 641.0, 682.5256446769547, 725.4522835259345, 733.7688628082137, 738.8263758016861, 4735.692180008288, 5059.468755780664, 5070.847874563769, 5102.209157580581, 5112.667676601009, 128939.0, 130335.0, 132623.3368833169, 132666.0, 135486.0, 67883.0, 97492.0, 106214.46892765487, 107382.0, 110211.0, 60949.69525152346, 83271.5836420814, 83574.58212708897, 84955.57522212349, 94372.52813736025, 10620.578490194335, 10823.621173258898, 11073.501692423699, 11145.721356965816, 11423.143264255381, 56004.0, 56144.157837631654, 56293.155602665174, 57205.713971429876, 57320.42679573151, 80677.0, 81380.0, 83250.16749832424, 83317.0, 83367.0, 14408.711825763636, 14415.711685766435, 14430.062045966979, 14450.205238711551, 14550.27248637585, 15494.84505154934, 15575.766363504783, 15648.2958266876, 15816.130112843904, 16543.0, 15342.079475231521, 15593.76609350838, 16224.269907853939, 16769.07770072658, 16824.66350673004, 14534.854651453774, 14936.925315373353, 15827.841721582638, 16007.199640018185, 16467.34130634761, 13333.066685331934, 13614.115082519604, 13714.93142534321, 13802.930985345009, 14132.929335353258, 11344.825347033331, 11725.648230553097, 12115.515379384726, 12157.0, 12510.937445312715, 11475.368854713071, 12755.158115601664, 12885.48458061667, 12948.15836970594, 13134.737305254033], "value": 8114.799850392581, "unit": "score"}], "name": "dromaeo_css", "value": 7755.355485032324, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 109.0, 108.0, 110.0, 108.0, 110.0, 109.0, 108.0, 110.0, 109.0], "value": 108.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 114.0, 116.0, 179.0, 119.0, 123.0, 152.0, 123.0, 163.0, 115.0], "value": 134.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 160.0, 160.0, 165.0, 160.0, 160.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 111.0, 77.0, 78.0, 75.0, 73.0, 98.0, 75.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 137.0, 83.0, 84.0, 83.0, 115.0, 84.0, 84.0, 122.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 50.0, 50.0, 50.0, 49.0, 51.0, 49.0, 59.0, 49.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 59.0, 59.0, 59.0, 58.0, 59.0, 59.0, 60.0, 60.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 80.0, 73.0, 77.0, 72.0, 72.0, 72.0, 72.0, 73.0, 72.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 126.0, 119.0, 117.0, 120.0, 115.0, 119.0, 113.0, 167.0, 120.0], "value": 123.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 158.0, 160.0, 158.0, 157.0, 154.0, 154.0, 154.0, 152.0, 157.0], "value": 155.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 64.0, 61.0, 61.0, 64.0, 61.0, 62.0, 63.0, 64.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1462.8, "alertThreshold": 2.0}]} 13:04:51 INFO - Return code: 0 13:04:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:04:51 INFO - # TBPL SUCCESS # 13:04:51 INFO - Running post-action listener: _resource_record_post_action 13:04:51 INFO - [mozharness: 2016-08-30 20:04:51.310049Z] Finished run-tests step (success) 13:04:51 INFO - Running post-run listener: _resource_record_post_run 13:04:51 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 602112; Write bytes: 327700480; Read time: 108; Write time: 396980 13:04:51 INFO - TinderboxPrint: CPU usage
12.9% 13:04:51 INFO - TinderboxPrint: I/O read bytes / time
602,112 / 108 13:04:51 INFO - TinderboxPrint: I/O write bytes / time
327,700,480 / 396,980 13:04:51 INFO - TinderboxPrint: CPU idle
8,276.4 (87.1%) 13:04:51 INFO - TinderboxPrint: CPU user
1,183.3 (12.4%) 13:04:51 INFO - TinderboxPrint: Swap in / out
0 / 0 13:04:51 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78524416; Read time: 0; Write time: 152768 13:04:51 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 598016; Write bytes: 248782848; Read time: 96; Write time: 244156 13:04:51 INFO - Running post-run listener: _upload_blobber_files 13:04:51 INFO - Blob upload gear active. 13:04:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:04:51 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 13:04:51 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'] 13:04:51 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 13:04:52 INFO - (blobuploader) - INFO - Open directory for files ... 13:04:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:04:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:04:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:04:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:04:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:04:57 INFO - (blobuploader) - INFO - Done attempting. 13:04:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:04:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:04:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:04:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:04:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:04:58 INFO - (blobuploader) - INFO - Done attempting. 13:04:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:04:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:04:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:04:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:04:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:04:59 INFO - (blobuploader) - INFO - Done attempting. 13:04:59 INFO - (blobuploader) - INFO - Iteration through files over. 13:04:59 INFO - Return code: 0 13:04:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:04:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:04:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95fea628b19a697426e95fcbc0cedb2787e82faff2243a490ac282780c19944cb2184ac9156ffa995bd4a4a1610f8336b3c73c1b71361bb170b222d180c7ac01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1351c5ff9563e23d38a1247f26f17246470879f0f21ac9e3f8eec38d58c582a120518af714eb1cd9bd465531e32f2993b96c0c71fc0284466e0d6c1232c4af4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f02be5b755c04293e365a35349611aad66eda1008a102f18426e6111abe0b5d5adb72f686ff28874db8e1da0d1a3ede424e615bfd4fccad408f784c92ce332bd"} 13:04:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:04:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:04:59 INFO - Contents: 13:04:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95fea628b19a697426e95fcbc0cedb2787e82faff2243a490ac282780c19944cb2184ac9156ffa995bd4a4a1610f8336b3c73c1b71361bb170b222d180c7ac01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1351c5ff9563e23d38a1247f26f17246470879f0f21ac9e3f8eec38d58c582a120518af714eb1cd9bd465531e32f2993b96c0c71fc0284466e0d6c1232c4af4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f02be5b755c04293e365a35349611aad66eda1008a102f18426e6111abe0b5d5adb72f686ff28874db8e1da0d1a3ede424e615bfd4fccad408f784c92ce332bd"} 13:04:59 INFO - Running post-run listener: copy_logs_to_upload_dir 13:04:59 INFO - Copying logs to upload dir... 13:04:59 INFO - mkdir: /builds/slave/test/build/upload/logs 13:04:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1680.297878 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-08-30 13:04:59.151897) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 13:04:59.154992) ========= 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=fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751 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/95fea628b19a697426e95fcbc0cedb2787e82faff2243a490ac282780c19944cb2184ac9156ffa995bd4a4a1610f8336b3c73c1b71361bb170b222d180c7ac01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1351c5ff9563e23d38a1247f26f17246470879f0f21ac9e3f8eec38d58c582a120518af714eb1cd9bd465531e32f2993b96c0c71fc0284466e0d6c1232c4af4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f02be5b755c04293e365a35349611aad66eda1008a102f18426e6111abe0b5d5adb72f686ff28874db8e1da0d1a3ede424e615bfd4fccad408f784c92ce332bd"} build_url:https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011322 build_url: 'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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/95fea628b19a697426e95fcbc0cedb2787e82faff2243a490ac282780c19944cb2184ac9156ffa995bd4a4a1610f8336b3c73c1b71361bb170b222d180c7ac01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1351c5ff9563e23d38a1247f26f17246470879f0f21ac9e3f8eec38d58c582a120518af714eb1cd9bd465531e32f2993b96c0c71fc0284466e0d6c1232c4af4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f02be5b755c04293e365a35349611aad66eda1008a102f18426e6111abe0b5d5adb72f686ff28874db8e1da0d1a3ede424e615bfd4fccad408f784c92ce332bd"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 13:04:59.179549) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:04:59.179862) ========= 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=fa44defc139c4ba8574fe040000001db-1472585734.99454-1128849751 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004436 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:04:59.261304) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 13:04:59.261604) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 13:04:59.261885) ========= ========= Total master_lag: 0.15 =========