builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-010 starttime: 1474398994.26 results: success (0) buildid: 20160920092516 builduid: 0d274f9195484d59a3b1b0319bf5c3a3 revision: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 12:16:34.259871) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 12:16:34.260819) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 12:16:34.261532) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 12:16:34.277292) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 12:16:34.277600) ========= 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=eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 12:16:34-- 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% 46.1M=0s 2016-09-20 12:16:34 (46.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104614 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 12:16:34.397588) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 12:16:34.397990) ========= 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=eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021995 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 12:16:34.451443) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 12:16:34.452336) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6be9a5c9c8cd25d2082376495b788d85ce3cca98 --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-beta --rev 6be9a5c9c8cd25d2082376495b788d85ce3cca98 --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=eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 12:16:34,589 truncating revision to first 12 chars 2016-09-20 12:16:34,589 Setting DEBUG logging. 2016-09-20 12:16:34,589 attempt 1/10 2016-09-20 12:16:34,589 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6be9a5c9c8cd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 12:16:35,338 unpacking tar archive at: mozilla-beta-6be9a5c9c8cd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.104984 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 12:16:35.575878) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 12:16:35.576642) ========= script_repo_revision: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 12:16:35.577606) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 12:16:35.578179) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 12:16:35.588604) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-20 12:16:35.588919) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:16:35 INFO - MultiFileLogger online at 20160920 12:16:35 in /builds/slave/test 12:16:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 12:16:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:16:35 INFO - {'append_to_log': False, 12:16:35 INFO - 'base_work_dir': '/builds/slave/test', 12:16:35 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:16:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:16:35 INFO - 'branch': 'Mozilla-Beta', 12:16:35 INFO - 'buildbot_json_path': 'buildprops.json', 12:16:35 INFO - 'config_files': ('talos/linux_config.py',), 12:16:35 INFO - 'default_actions': ('clobber', 12:16:35 INFO - 'read-buildbot-config', 12:16:35 INFO - 'download-and-extract', 12:16:35 INFO - 'populate-webroot', 12:16:35 INFO - 'create-virtualenv', 12:16:35 INFO - 'install', 12:16:35 INFO - 'run-tests'), 12:16:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:16:35 INFO - 'download_minidump_stackwalk': True, 12:16:35 INFO - 'download_symbols': 'ondemand', 12:16:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:16:35 INFO - 'tooltool.py': '/tools/tooltool.py', 12:16:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:16:35 INFO - '/tools/misc-python/virtualenv.py')}, 12:16:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:16:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:16:35 INFO - 'installer_path': 'installer.exe', 12:16:35 INFO - 'log_level': 'info', 12:16:35 INFO - 'log_name': 'talos', 12:16:35 INFO - 'log_to_console': True, 12:16:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:16:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:16:35 INFO - 'opt_config_files': (), 12:16:35 INFO - 'pip_index': False, 12:16:35 INFO - 'sps_profile': False, 12:16:35 INFO - 'sps_profile_interval': 0, 12:16:35 INFO - 'suite': 'dromaeojs', 12:16:35 INFO - 'system_bits': '32', 12:16:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:16:35 INFO - 'title': 'talos-linux64-ix-010', 12:16:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:16:35 INFO - 'use_talos_json': True, 12:16:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:16:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:16:35 INFO - 'work_dir': 'build'} 12:16:35 INFO - [mozharness: 2016-09-20 19:16:35.809580Z] Running clobber step. 12:16:35 INFO - Running pre-action listener: _resource_record_pre_action 12:16:35 INFO - Running main action method: clobber 12:16:35 INFO - rmtree: /builds/slave/test/build 12:16:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:16:36 INFO - Running post-action listener: _resource_record_post_action 12:16:36 INFO - [mozharness: 2016-09-20 19:16:36.274472Z] Finished clobber step (success) 12:16:36 INFO - [mozharness: 2016-09-20 19:16:36.274573Z] Running read-buildbot-config step. 12:16:36 INFO - Running pre-action listener: _resource_record_pre_action 12:16:36 INFO - Running main action method: read_buildbot_config 12:16:36 INFO - Using buildbot properties: 12:16:36 INFO - { 12:16:36 INFO - "project": "", 12:16:36 INFO - "product": "firefox", 12:16:36 INFO - "script_repo_revision": "production", 12:16:36 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 12:16:36 INFO - "repository": "", 12:16:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 12:16:36 INFO - "buildid": "20160920092516", 12:16:36 INFO - "pgo_build": "True", 12:16:36 INFO - "basedir": "/builds/slave/test", 12:16:36 INFO - "buildnumber": 163, 12:16:36 INFO - "slavename": "talos-linux64-ix-010", 12:16:36 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:16:36 INFO - "platform": "ubuntu64_hw", 12:16:36 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 12:16:36 INFO - "branch": "mozilla-beta", 12:16:36 INFO - "repo_path": "releases/mozilla-beta", 12:16:36 INFO - "revision": "6be9a5c9c8cd25d2082376495b788d85ce3cca98", 12:16:36 INFO - "stage_platform": "linux64-pgo", 12:16:36 INFO - "builduid": "0d274f9195484d59a3b1b0319bf5c3a3", 12:16:36 INFO - "slavebuilddir": "test" 12:16:36 INFO - } 12:16:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 12:16:36 INFO - Running post-action listener: _resource_record_post_action 12:16:36 INFO - [mozharness: 2016-09-20 19:16:36.277027Z] Finished read-buildbot-config step (success) 12:16:36 INFO - [mozharness: 2016-09-20 19:16:36.277119Z] Running download-and-extract step. 12:16:36 INFO - Running pre-action listener: _resource_record_pre_action 12:16:36 INFO - Running main action method: download_and_extract 12:16:36 INFO - mkdir: /builds/slave/test/build/tests 12:16:36 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:16:36 INFO - https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:16:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:16:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:16:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:16:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 12:16:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:16:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:16:36 INFO - retry: Failed, sleeping 30 seconds before retrying 12:17:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 12:17:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:17:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:17:06 INFO - retry: Failed, sleeping 60 seconds before retrying 12:18:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 12:18:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:18:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:18:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 12:18:06 INFO - Caught exception: HTTP Error 404: Not Found 12:18:06 INFO - Caught exception: HTTP Error 404: Not Found 12:18:06 INFO - Caught exception: HTTP Error 404: Not Found 12:18:06 INFO - trying https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:18:06 INFO - Downloading https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:18:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 12:18:06 INFO - Downloaded 1327 bytes. 12:18:06 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:18:06 INFO - Using the following test package requirements: 12:18:06 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 12:18:06 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:18:06 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 12:18:06 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:18:06 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 12:18:06 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:18:06 INFO - u'jsshell-linux-x86_64.zip'], 12:18:06 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:18:06 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 12:18:06 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 12:18:06 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:18:06 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 12:18:06 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:18:06 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 12:18:06 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:18:06 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 12:18:06 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:18:06 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 12:18:06 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:18: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:18:06 INFO - https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:18:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:18:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:18:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:18:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:18:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:18:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:18:06 INFO - retry: Failed, sleeping 30 seconds before retrying 12:18:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 12:18:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:18:37 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:18:37 INFO - retry: Failed, sleeping 60 seconds before retrying 12:19:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 12:19:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:19:37 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:19:37 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 12:19:37 INFO - Caught exception: HTTP Error 404: Not Found 12:19:37 INFO - Caught exception: HTTP Error 404: Not Found 12:19:37 INFO - Caught exception: HTTP Error 404: Not Found 12:19:37 INFO - trying https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:19:37 INFO - Downloading https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:19:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:19:37 INFO - Downloaded 21769913 bytes. 12:19:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:19:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 12:19:39 INFO - Return code: 0 12:19:39 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:19:39 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:19:39 INFO - https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:19:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:19:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:19:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:19:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:19:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:19:39 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:19:39 INFO - retry: Failed, sleeping 30 seconds before retrying 12:20:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 12:20:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:20:09 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:20:09 INFO - retry: Failed, sleeping 60 seconds before retrying 12:21:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 12:21:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:21:09 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:21:09 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 12:21:09 INFO - Caught exception: HTTP Error 404: Not Found 12:21:09 INFO - Caught exception: HTTP Error 404: Not Found 12:21:09 INFO - Caught exception: HTTP Error 404: Not Found 12:21:09 INFO - trying https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:21:09 INFO - Downloading https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:21:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:21:10 INFO - Downloaded 21769913 bytes. 12:21:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:21:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 12:21:11 INFO - Return code: 0 12:21:11 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:21:11 INFO - https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:21:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:21:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:21:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:21:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:21:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:21:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:21:11 INFO - retry: Failed, sleeping 30 seconds before retrying 12:21:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 12:21:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:21:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:21:41 INFO - retry: Failed, sleeping 60 seconds before retrying 12:22:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 12:22:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:22:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:22:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 12:22:41 INFO - Caught exception: HTTP Error 404: Not Found 12:22:41 INFO - Caught exception: HTTP Error 404: Not Found 12:22:41 INFO - Caught exception: HTTP Error 404: Not Found 12:22:41 INFO - trying https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:22:41 INFO - Downloading https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:22:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:22:42 INFO - Downloaded 14413740 bytes. 12:22:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:22:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 12:22:42 INFO - Return code: 0 12:22:42 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:22:42 INFO - https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:22:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:22:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:22:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:22:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:22:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:22:42 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:22:42 INFO - retry: Failed, sleeping 30 seconds before retrying 12:23:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 12:23:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:23:12 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:23:12 INFO - retry: Failed, sleeping 60 seconds before retrying 12:24:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 12:24:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:24:12 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:24:12 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 12:24:12 INFO - Caught exception: HTTP Error 404: Not Found 12:24:12 INFO - Caught exception: HTTP Error 404: Not Found 12:24:12 INFO - Caught exception: HTTP Error 404: Not Found 12:24:12 INFO - trying https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:24:12 INFO - Downloading https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:24:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:24:13 INFO - Downloaded 56771801 bytes. 12:24:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:24:13 INFO - mkdir: /builds/slave/test/properties 12:24:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:24:13 INFO - Writing to file /builds/slave/test/properties/build_url 12:24:13 INFO - Contents: 12:24:13 INFO - build_url:https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:24:14 INFO - Running post-action listener: _resource_record_post_action 12:24:14 INFO - Running post-action listener: set_extra_try_arguments 12:24:14 INFO - [mozharness: 2016-09-20 19:24:14.511020Z] Finished download-and-extract step (success) 12:24:14 INFO - [mozharness: 2016-09-20 19:24:14.511253Z] Running populate-webroot step. 12:24:14 INFO - Running pre-action listener: _resource_record_pre_action 12:24:14 INFO - Running main action method: populate_webroot 12:24:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:24:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:24:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:24:14 INFO - '--tppagecycles', 12:24:14 INFO - '7'], 12:24:14 INFO - 'tests': ['tsvgm']}}, 12:24:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:24:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:24:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:24:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:24:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:24:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:24:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:24:14 INFO - 'talos_options': ['--disable-e10s'], 12:24:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:24:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:24:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:24:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:24:14 INFO - 'talos_options': ['--disable-e10s'], 12:24:14 INFO - 'tests': ['damp', 'tps']}, 12:24:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:24:14 INFO - 'tests': ['damp', 'tps']}, 12:24:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:24:14 INFO - 'tests': ['dromaeo_dom']}, 12:24:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:24:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:24:14 INFO - 'tests': ['basic_compositor_video']}, 12:24:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:24:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:24:14 INFO - 'tests': ['a11yr', 12:24:14 INFO - 'ts_paint', 12:24:14 INFO - 'tpaint', 12:24:14 INFO - 'sessionrestore', 12:24:14 INFO - 'sessionrestore_no_auto_restore', 12:24:14 INFO - 'tabpaint']}, 12:24:14 INFO - 'other-e10s': {'tests': ['a11yr', 12:24:14 INFO - 'ts_paint', 12:24:14 INFO - 'tpaint', 12:24:14 INFO - 'sessionrestore', 12:24:14 INFO - 'sessionrestore_no_auto_restore', 12:24:14 INFO - 'tabpaint']}, 12:24:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:24:14 INFO - 'ts_paint', 12:24:14 INFO - 'tpaint', 12:24:14 INFO - 'sessionrestore', 12:24:14 INFO - 'sessionrestore_no_auto_restore', 12:24:14 INFO - 'tabpaint']}, 12:24:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:24:14 INFO - 'ts_paint', 12:24:14 INFO - 'tpaint', 12:24:14 INFO - 'sessionrestore', 12:24:14 INFO - 'sessionrestore_no_auto_restore', 12:24:14 INFO - 'tabpaint']}, 12:24:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:24:14 INFO - 'tests': ['a11yr', 12:24:14 INFO - 'ts_paint', 12:24:14 INFO - 'tpaint', 12:24:14 INFO - 'sessionrestore', 12:24:14 INFO - 'sessionrestore_no_auto_restore', 12:24:14 INFO - 'tabpaint']}, 12:24:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:24:14 INFO - 'tests': ['a11yr', 12:24:14 INFO - 'ts_paint', 12:24:14 INFO - 'tpaint', 12:24:14 INFO - 'sessionrestore', 12:24:14 INFO - 'sessionrestore_no_auto_restore', 12:24:14 INFO - 'tabpaint']}, 12:24:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:24:14 INFO - 'tests': ['tsvgx', 12:24:14 INFO - 'tsvgr_opacity', 12:24:14 INFO - 'tart', 12:24:14 INFO - 'tscrollx', 12:24:14 INFO - 'cart']}, 12:24:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:24:14 INFO - 'tsvgr_opacity', 12:24:14 INFO - 'tart', 12:24:14 INFO - 'tscrollx', 12:24:14 INFO - 'cart']}, 12:24:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:24:14 INFO - 'talos_options': ['--disable-e10s'], 12:24:14 INFO - 'tests': ['tp5o']}, 12:24:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:24:14 INFO - 'tests': ['tp5o']}, 12:24:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:24:14 INFO - 'talos_options': ['--disable-e10s', 12:24:14 INFO - '--xperf_path', 12:24:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:24:14 INFO - 'tests': ['tp5n']}, 12:24:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:24:14 INFO - 'talos_options': ['--xperf_path', 12:24:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:24:14 INFO - 'tests': ['tp5n']}}, 12:24:14 INFO - 'talos.zip': {'path': '', 12:24:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:24:14 INFO - Running post-action listener: _resource_record_post_action 12:24:14 INFO - [mozharness: 2016-09-20 19:24:14.528268Z] Finished populate-webroot step (success) 12:24:14 INFO - [mozharness: 2016-09-20 19:24:14.528357Z] Running create-virtualenv step. 12:24:14 INFO - Running pre-action listener: _resource_record_pre_action 12:24:14 INFO - Running main action method: create_virtualenv 12:24:14 INFO - Creating virtualenv /builds/slave/test/build/venv 12:24:14 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:24:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:24:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:24:14 INFO - Using real prefix '/usr' 12:24:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:24:15 INFO - Installing distribute.............................................................................................................................................................................................done. 12:24:17 INFO - Installing pip.................done. 12:24:17 INFO - Return code: 0 12:24:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:24:17 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:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:17 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:24:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:17 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 0x13d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x169a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16983b0>, '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 0x16992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1699740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', '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:24:17 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:24:17 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:24:17 INFO - Using env: {'DISPLAY': ':0', 12:24:17 INFO - 'HOME': '/home/cltbld', 12:24:17 INFO - 'LANG': 'en_US.UTF-8', 12:24:17 INFO - 'LANGUAGE': 'en_US:en', 12:24:17 INFO - 'LOGNAME': 'cltbld', 12:24:17 INFO - 'MAIL': '/var/mail/cltbld', 12:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:17 INFO - 'MOZ_NO_REMOTE': '1', 12:24:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:24:17 INFO - 'NO_EM_RESTART': '1', 12:24:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:17 INFO - 'PWD': '/builds/slave/test', 12:24:17 INFO - 'SHELL': '/bin/bash', 12:24:17 INFO - 'SHLVL': '1', 12:24:17 INFO - 'TERM': 'linux', 12:24:17 INFO - 'TMOUT': '86400', 12:24:17 INFO - 'USER': 'cltbld', 12:24:17 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', 12:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:17 INFO - '_': '/tools/buildbot/bin/python'} 12:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:17 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:24:17 INFO - Cleaning up... 12:24:17 INFO - Return code: 0 12:24:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:24:17 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:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:17 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:24:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:17 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 0x13d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x169a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16983b0>, '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 0x16992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1699740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', '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:24:17 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:24:17 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:24:17 INFO - Using env: {'DISPLAY': ':0', 12:24:17 INFO - 'HOME': '/home/cltbld', 12:24:17 INFO - 'LANG': 'en_US.UTF-8', 12:24:17 INFO - 'LANGUAGE': 'en_US:en', 12:24:17 INFO - 'LOGNAME': 'cltbld', 12:24:17 INFO - 'MAIL': '/var/mail/cltbld', 12:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:17 INFO - 'MOZ_NO_REMOTE': '1', 12:24:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:24:17 INFO - 'NO_EM_RESTART': '1', 12:24:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:17 INFO - 'PWD': '/builds/slave/test', 12:24:17 INFO - 'SHELL': '/bin/bash', 12:24:17 INFO - 'SHLVL': '1', 12:24:17 INFO - 'TERM': 'linux', 12:24:17 INFO - 'TMOUT': '86400', 12:24:17 INFO - 'USER': 'cltbld', 12:24:17 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', 12:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:17 INFO - '_': '/tools/buildbot/bin/python'} 12:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:17 INFO - Downloading/unpacking psutil>=3.1.1 12:24:17 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:24:17 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:24:17 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:24:17 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:24:17 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:24:17 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:24:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:24:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:24:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:24:19 INFO - Installing collected packages: psutil 12:24:19 INFO - Running setup.py install for psutil 12:24:19 INFO - building 'psutil._psutil_linux' extension 12:24:19 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:24:19 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:24:19 INFO - building 'psutil._psutil_posix' extension 12:24:19 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:24:19 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:24:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:24:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:24:20 INFO - Successfully installed psutil 12:24:20 INFO - Cleaning up... 12:24:20 INFO - Return code: 0 12:24:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:24:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:24:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:20 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 0x13d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x169a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16983b0>, '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 0x16992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1699740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', '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:24:20 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:24:20 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:24:20 INFO - Using env: {'DISPLAY': ':0', 12:24:20 INFO - 'HOME': '/home/cltbld', 12:24:20 INFO - 'LANG': 'en_US.UTF-8', 12:24:20 INFO - 'LANGUAGE': 'en_US:en', 12:24:20 INFO - 'LOGNAME': 'cltbld', 12:24:20 INFO - 'MAIL': '/var/mail/cltbld', 12:24:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:20 INFO - 'MOZ_NO_REMOTE': '1', 12:24:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:24:20 INFO - 'NO_EM_RESTART': '1', 12:24:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:24:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:20 INFO - 'PWD': '/builds/slave/test', 12:24:20 INFO - 'SHELL': '/bin/bash', 12:24:20 INFO - 'SHLVL': '1', 12:24:20 INFO - 'TERM': 'linux', 12:24:20 INFO - 'TMOUT': '86400', 12:24:20 INFO - 'USER': 'cltbld', 12:24:20 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', 12:24:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:20 INFO - '_': '/tools/buildbot/bin/python'} 12:24:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:24:20 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:24:20 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:24:20 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:24:20 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:24:20 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:24:20 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:24:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:24:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:24:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:24:22 INFO - Installing collected packages: mozsystemmonitor 12:24:22 INFO - Running setup.py install for mozsystemmonitor 12:24:22 INFO - Successfully installed mozsystemmonitor 12:24:22 INFO - Cleaning up... 12:24:22 INFO - Return code: 0 12:24:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:24:22 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:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:22 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:24:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:22 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 0x13d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x169a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16983b0>, '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 0x16992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1699740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', '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:24:22 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:24:22 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:24:22 INFO - Using env: {'DISPLAY': ':0', 12:24:22 INFO - 'HOME': '/home/cltbld', 12:24:22 INFO - 'LANG': 'en_US.UTF-8', 12:24:22 INFO - 'LANGUAGE': 'en_US:en', 12:24:22 INFO - 'LOGNAME': 'cltbld', 12:24:22 INFO - 'MAIL': '/var/mail/cltbld', 12:24:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:22 INFO - 'MOZ_NO_REMOTE': '1', 12:24:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:24:22 INFO - 'NO_EM_RESTART': '1', 12:24:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:24:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:22 INFO - 'PWD': '/builds/slave/test', 12:24:22 INFO - 'SHELL': '/bin/bash', 12:24:22 INFO - 'SHLVL': '1', 12:24:22 INFO - 'TERM': 'linux', 12:24:22 INFO - 'TMOUT': '86400', 12:24:22 INFO - 'USER': 'cltbld', 12:24:22 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', 12:24:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:22 INFO - '_': '/tools/buildbot/bin/python'} 12:24:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:22 INFO - Downloading/unpacking jsonschema==2.5.1 12:24:22 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:24:22 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:24:22 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:24:22 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:24:22 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:24:22 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:24:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:24:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:24:24 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:24:24 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:24:24 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:24:24 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:24:24 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:24:24 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:24:24 INFO - Downloading functools32-3.2.3-2.tar.gz 12:24:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:24:24 INFO - warning: no files found matching '*.txt' 12:24:24 INFO - no previously-included directories found matching 'build' 12:24:24 INFO - no previously-included directories found matching 'dist' 12:24:24 INFO - no previously-included directories found matching '.git*' 12:24:24 INFO - Installing collected packages: jsonschema, functools32 12:24:24 INFO - Running setup.py install for functools32 12:24:24 INFO - warning: no files found matching '*.txt' 12:24:24 INFO - no previously-included directories found matching 'build' 12:24:24 INFO - no previously-included directories found matching 'dist' 12:24:24 INFO - no previously-included directories found matching '.git*' 12:24:24 INFO - Successfully installed jsonschema functools32 12:24:24 INFO - Cleaning up... 12:24:24 INFO - Return code: 0 12:24:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:24:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:24:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:24:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x169a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16983b0>, '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 0x16992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1699740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', '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:24:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:24:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:24:24 INFO - Using env: {'DISPLAY': ':0', 12:24:24 INFO - 'HOME': '/home/cltbld', 12:24:24 INFO - 'LANG': 'en_US.UTF-8', 12:24:24 INFO - 'LANGUAGE': 'en_US:en', 12:24:24 INFO - 'LOGNAME': 'cltbld', 12:24:24 INFO - 'MAIL': '/var/mail/cltbld', 12:24:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:24 INFO - 'MOZ_NO_REMOTE': '1', 12:24:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:24:24 INFO - 'NO_EM_RESTART': '1', 12:24:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:24:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:24 INFO - 'PWD': '/builds/slave/test', 12:24:24 INFO - 'SHELL': '/bin/bash', 12:24:24 INFO - 'SHLVL': '1', 12:24:24 INFO - 'TERM': 'linux', 12:24:24 INFO - 'TMOUT': '86400', 12:24:24 INFO - 'USER': 'cltbld', 12:24:24 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', 12:24:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:24 INFO - '_': '/tools/buildbot/bin/python'} 12:24:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:24:25 INFO - Cleaning up... 12:24:25 INFO - Return code: 0 12:24:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:24:25 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:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:25 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:24:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:25 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 0x13d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x169a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16983b0>, '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 0x16992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1699740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', '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:24:25 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:24:25 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:24:25 INFO - Using env: {'DISPLAY': ':0', 12:24:25 INFO - 'HOME': '/home/cltbld', 12:24:25 INFO - 'LANG': 'en_US.UTF-8', 12:24:25 INFO - 'LANGUAGE': 'en_US:en', 12:24:25 INFO - 'LOGNAME': 'cltbld', 12:24:25 INFO - 'MAIL': '/var/mail/cltbld', 12:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:25 INFO - 'MOZ_NO_REMOTE': '1', 12:24:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:24:25 INFO - 'NO_EM_RESTART': '1', 12:24:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:25 INFO - 'PWD': '/builds/slave/test', 12:24:25 INFO - 'SHELL': '/bin/bash', 12:24:25 INFO - 'SHLVL': '1', 12:24:25 INFO - 'TERM': 'linux', 12:24:25 INFO - 'TMOUT': '86400', 12:24:25 INFO - 'USER': 'cltbld', 12:24:25 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', 12:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:25 INFO - '_': '/tools/buildbot/bin/python'} 12:24:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:25 INFO - Downloading/unpacking blobuploader==1.2.4 12:24:25 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:24:25 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:24:25 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:24:25 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:24:25 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:24:25 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:24:27 INFO - Downloading blobuploader-1.2.4.tar.gz 12:24:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:24:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:24:27 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:24:27 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:24:27 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:24:27 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:24:27 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:24:27 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:24:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:24:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:24:27 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:24:27 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:24:27 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:24:27 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:24:27 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:24:27 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:24:27 INFO - Downloading docopt-0.6.1.tar.gz 12:24:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:24:27 INFO - Installing collected packages: blobuploader, requests, docopt 12:24:27 INFO - Running setup.py install for blobuploader 12:24:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:24:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:24:28 INFO - Running setup.py install for requests 12:24:28 INFO - Running setup.py install for docopt 12:24:28 INFO - Successfully installed blobuploader requests docopt 12:24:28 INFO - Cleaning up... 12:24:28 INFO - Return code: 0 12:24:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:24:28 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:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:28 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:24:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:28 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 0x13d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x169a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16983b0>, '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 0x16992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1699740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', '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:24:28 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:24:28 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:24:28 INFO - Using env: {'DISPLAY': ':0', 12:24:28 INFO - 'HOME': '/home/cltbld', 12:24:28 INFO - 'LANG': 'en_US.UTF-8', 12:24:28 INFO - 'LANGUAGE': 'en_US:en', 12:24:28 INFO - 'LOGNAME': 'cltbld', 12:24:28 INFO - 'MAIL': '/var/mail/cltbld', 12:24:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:28 INFO - 'MOZ_NO_REMOTE': '1', 12:24:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:24:28 INFO - 'NO_EM_RESTART': '1', 12:24:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:24:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:28 INFO - 'PWD': '/builds/slave/test', 12:24:28 INFO - 'SHELL': '/bin/bash', 12:24:28 INFO - 'SHLVL': '1', 12:24:28 INFO - 'TERM': 'linux', 12:24:28 INFO - 'TMOUT': '86400', 12:24:28 INFO - 'USER': 'cltbld', 12:24:28 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', 12:24:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:28 INFO - '_': '/tools/buildbot/bin/python'} 12:24:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:24:28 INFO - Running setup.py (path:/tmp/pip-gQo2jT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:24:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:24:28 INFO - Running setup.py (path:/tmp/pip-erTCrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:24:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:24:28 INFO - Running setup.py (path:/tmp/pip-KU0tM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:24:29 INFO - Running setup.py (path:/tmp/pip-zjMkv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:24:29 INFO - Running setup.py (path:/tmp/pip-E1T38n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:24:29 INFO - Running setup.py (path:/tmp/pip-O3u9C4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:24:29 INFO - Running setup.py (path:/tmp/pip-aRvbXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:24:29 INFO - Running setup.py (path:/tmp/pip-xPk3MT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:24:29 INFO - Running setup.py (path:/tmp/pip-oMNgFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:24:29 INFO - Running setup.py (path:/tmp/pip-aakuY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:24:29 INFO - Running setup.py (path:/tmp/pip-wtN8jx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:24:29 INFO - Running setup.py (path:/tmp/pip-VzNWMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:24:29 INFO - Running setup.py (path:/tmp/pip-2aR6sl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:24:29 INFO - Running setup.py (path:/tmp/pip-g7Vmu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:24:29 INFO - Running setup.py (path:/tmp/pip-tld9dg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:24:29 INFO - Running setup.py (path:/tmp/pip-V8GHeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:24:29 INFO - Running setup.py (path:/tmp/pip-ThWRdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:24:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:24:30 INFO - Running setup.py install for manifestparser 12:24:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:24:30 INFO - Running setup.py install for mozcrash 12:24:30 INFO - Running setup.py install for mozdebug 12:24:30 INFO - Running setup.py install for mozdevice 12:24:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:24:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:24:30 INFO - Running setup.py install for mozfile 12:24:30 INFO - Running setup.py install for mozhttpd 12:24:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:24:30 INFO - Running setup.py install for mozinfo 12:24:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:24:30 INFO - Running setup.py install for mozInstall 12:24:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:24:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:24:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:24:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:24:30 INFO - Running setup.py install for mozleak 12:24:30 INFO - Running setup.py install for mozlog 12:24:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:24:31 INFO - Running setup.py install for moznetwork 12:24:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:24:31 INFO - Running setup.py install for mozprocess 12:24:31 INFO - Running setup.py install for mozprofile 12:24:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:24:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:24:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:24:31 INFO - Running setup.py install for mozrunner 12:24:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:24:31 INFO - Running setup.py install for mozscreenshot 12:24:31 INFO - Running setup.py install for moztest 12:24:31 INFO - Running setup.py install for mozversion 12:24:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:24:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:24:31 INFO - Cleaning up... 12:24:31 INFO - Return code: 0 12:24:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:24:31 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:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:31 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:24:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:31 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 0x13d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x169a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16983b0>, '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 0x16992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1699740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', '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:24:31 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:24:31 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:24:31 INFO - Using env: {'DISPLAY': ':0', 12:24:31 INFO - 'HOME': '/home/cltbld', 12:24:31 INFO - 'LANG': 'en_US.UTF-8', 12:24:31 INFO - 'LANGUAGE': 'en_US:en', 12:24:31 INFO - 'LOGNAME': 'cltbld', 12:24:31 INFO - 'MAIL': '/var/mail/cltbld', 12:24:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:31 INFO - 'MOZ_NO_REMOTE': '1', 12:24:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:24:31 INFO - 'NO_EM_RESTART': '1', 12:24:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:24:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:31 INFO - 'PWD': '/builds/slave/test', 12:24:31 INFO - 'SHELL': '/bin/bash', 12:24:31 INFO - 'SHLVL': '1', 12:24:31 INFO - 'TERM': 'linux', 12:24:31 INFO - 'TMOUT': '86400', 12:24:31 INFO - 'USER': 'cltbld', 12:24:31 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', 12:24:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:31 INFO - '_': '/tools/buildbot/bin/python'} 12:24:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:24:32 INFO - Running setup.py (path:/tmp/pip-DeyRv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:24:32 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:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:24:32 INFO - Running setup.py (path:/tmp/pip-jLgS0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:24:32 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:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:24:32 INFO - Running setup.py (path:/tmp/pip-v8s7Kg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:24:32 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:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:24:32 INFO - Running setup.py (path:/tmp/pip-aclABW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:24:32 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:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:24:32 INFO - Running setup.py (path:/tmp/pip-hAp9mP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:24:32 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:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:24:32 INFO - Running setup.py (path:/tmp/pip-fLFJul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:24:32 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:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:24:32 INFO - Running setup.py (path:/tmp/pip-Y6iqma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:24:32 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:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:24:32 INFO - Running setup.py (path:/tmp/pip-5ysxDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:24:32 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:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:24:32 INFO - Running setup.py (path:/tmp/pip-IZWR9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:24:32 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:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:24:32 INFO - Running setup.py (path:/tmp/pip-UBfJH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:24:32 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:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:24:32 INFO - Running setup.py (path:/tmp/pip-flDclu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:24:32 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:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:24:32 INFO - Running setup.py (path:/tmp/pip-S89wxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:24:32 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:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:24:32 INFO - Running setup.py (path:/tmp/pip-w0qxrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:24:32 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:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:24:32 INFO - Running setup.py (path:/tmp/pip-hjxEhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:24:33 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:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:24:33 INFO - Running setup.py (path:/tmp/pip-9eZfpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:24:33 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:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:24:33 INFO - Running setup.py (path:/tmp/pip-9Qefjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:24:33 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:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:24:33 INFO - Running setup.py (path:/tmp/pip-UdaVAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:24:33 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:24:33 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:24:33 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:24:33 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:24:33 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:24:33 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:24:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:24:33 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:24:33 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:24:33 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:24:33 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:24:33 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:24:33 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:24:35 INFO - Downloading blessings-1.6.tar.gz 12:24:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:24:35 INFO - Installing collected packages: blessings 12:24:35 INFO - Running setup.py install for blessings 12:24:35 INFO - Successfully installed blessings 12:24:35 INFO - Cleaning up... 12:24:35 INFO - Return code: 0 12:24:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:24:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:24:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:24:35 INFO - Reading from file tmpfile_stdout 12:24:35 INFO - Current package versions: 12:24:35 INFO - argparse == 1.2.1 12:24:35 INFO - blessings == 1.6 12:24:35 INFO - blobuploader == 1.2.4 12:24:35 INFO - docopt == 0.6.1 12:24:35 INFO - functools32 == 3.2.3-2 12:24:35 INFO - jsonschema == 2.5.1 12:24:35 INFO - manifestparser == 1.1 12:24:35 INFO - mozInstall == 1.12 12:24:35 INFO - mozcrash == 0.17 12:24:35 INFO - mozdebug == 0.1 12:24:35 INFO - mozdevice == 0.48 12:24:35 INFO - mozfile == 1.2 12:24:35 INFO - mozhttpd == 0.7 12:24:35 INFO - mozinfo == 0.9 12:24:35 INFO - mozleak == 0.1 12:24:35 INFO - mozlog == 3.3 12:24:35 INFO - moznetwork == 0.27 12:24:35 INFO - mozprocess == 0.23 12:24:35 INFO - mozprofile == 0.28 12:24:35 INFO - mozrunner == 6.12 12:24:35 INFO - mozscreenshot == 0.1 12:24:35 INFO - mozsystemmonitor == 0.3 12:24:35 INFO - moztest == 0.7 12:24:35 INFO - mozversion == 1.4 12:24:35 INFO - psutil == 3.1.1 12:24:35 INFO - requests == 1.2.3 12:24:35 INFO - wsgiref == 0.1.2 12:24:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:24: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:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24: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:24:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:35 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 0x13d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x169a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16983b0>, '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 0x16992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1699740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', '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:24:35 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:24:35 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:24:35 INFO - Using env: {'DISPLAY': ':0', 12:24:35 INFO - 'HOME': '/home/cltbld', 12:24:35 INFO - 'LANG': 'en_US.UTF-8', 12:24:35 INFO - 'LANGUAGE': 'en_US:en', 12:24:35 INFO - 'LOGNAME': 'cltbld', 12:24:35 INFO - 'MAIL': '/var/mail/cltbld', 12:24:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:35 INFO - 'MOZ_NO_REMOTE': '1', 12:24:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:24:35 INFO - 'NO_EM_RESTART': '1', 12:24:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:24:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:35 INFO - 'PWD': '/builds/slave/test', 12:24:35 INFO - 'SHELL': '/bin/bash', 12:24:35 INFO - 'SHLVL': '1', 12:24:35 INFO - 'TERM': 'linux', 12:24:35 INFO - 'TMOUT': '86400', 12:24:35 INFO - 'USER': 'cltbld', 12:24:35 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', 12:24:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:35 INFO - '_': '/tools/buildbot/bin/python'} 12:24:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:35 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:24:35 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:24:35 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:24:35 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:24:35 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:24:35 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:24:35 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:24:35 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:24:35 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:24:35 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:24:35 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:24:35 INFO - Cleaning up... 12:24:35 INFO - Return code: 0 12:24:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:24: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:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24: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:24:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:35 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 0x13d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x169a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16983b0>, '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 0x16992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1699740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', '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:24:35 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:24:35 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:24:35 INFO - Using env: {'DISPLAY': ':0', 12:24:35 INFO - 'HOME': '/home/cltbld', 12:24:35 INFO - 'LANG': 'en_US.UTF-8', 12:24:35 INFO - 'LANGUAGE': 'en_US:en', 12:24:35 INFO - 'LOGNAME': 'cltbld', 12:24:35 INFO - 'MAIL': '/var/mail/cltbld', 12:24:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:35 INFO - 'MOZ_NO_REMOTE': '1', 12:24:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:24:35 INFO - 'NO_EM_RESTART': '1', 12:24:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:24:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:35 INFO - 'PWD': '/builds/slave/test', 12:24:35 INFO - 'SHELL': '/bin/bash', 12:24:35 INFO - 'SHLVL': '1', 12:24:35 INFO - 'TERM': 'linux', 12:24:35 INFO - 'TMOUT': '86400', 12:24:35 INFO - 'USER': 'cltbld', 12:24:35 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', 12:24:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:35 INFO - '_': '/tools/buildbot/bin/python'} 12:24:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:24:36 INFO - Cleaning up... 12:24:36 INFO - Return code: 0 12:24:36 INFO - Running post-action listener: _resource_record_post_action 12:24:36 INFO - Running post-action listener: _start_resource_monitoring 12:24:36 INFO - Starting resource monitoring. 12:24:36 INFO - [mozharness: 2016-09-20 19:24:36.084605Z] Finished create-virtualenv step (success) 12:24:36 INFO - [mozharness: 2016-09-20 19:24:36.085140Z] Running install step. 12:24:36 INFO - Running pre-action listener: _resource_record_pre_action 12:24:36 INFO - Running main action method: install 12:24:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:24:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:24:36 INFO - Reading from file tmpfile_stdout 12:24:36 INFO - Detecting whether we're running mozinstall >=1.0... 12:24:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:24:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:24:36 INFO - Reading from file tmpfile_stdout 12:24:36 INFO - Output received: 12:24:36 INFO - Usage: mozinstall [options] installer 12:24:36 INFO - Options: 12:24:36 INFO - -h, --help show this help message and exit 12:24:36 INFO - -d DEST, --destination=DEST 12:24:36 INFO - Directory to install application into. [default: 12:24:36 INFO - "/builds/slave/test"] 12:24:36 INFO - --app=APP Application being installed. [default: firefox] 12:24:36 INFO - mkdir: /builds/slave/test/build/application 12:24:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:24:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:24:50 INFO - Reading from file tmpfile_stdout 12:24:50 INFO - Output received: 12:24:50 INFO - /builds/slave/test/build/application/firefox/firefox 12:24:50 INFO - Running post-action listener: _resource_record_post_action 12:24:50 INFO - [mozharness: 2016-09-20 19:24:50.598263Z] Finished install step (success) 12:24:50 INFO - [mozharness: 2016-09-20 19:24:50.598542Z] Running run-tests step. 12:24:50 INFO - Running pre-action listener: _resource_record_pre_action 12:24:50 INFO - Running main action method: run_tests 12:24:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:24:50 INFO - Python 2.7.3 12:24:50 INFO - Return code: 0 12:24:50 INFO - grabbing minidump binary from tooltool 12:24:50 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:24:50 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 0x16983b0>, '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 0x16992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1699740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:24:50 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:24:50 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:24:50 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:24:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:24:50 INFO - Return code: 0 12:24:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:24:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:24:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:24:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:24:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:24:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-010 --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:24:50 INFO - Using env: {'DISPLAY': ':0', 12:24:50 INFO - 'HOME': '/home/cltbld', 12:24:50 INFO - 'LANG': 'en_US.UTF-8', 12:24:50 INFO - 'LANGUAGE': 'en_US:en', 12:24:50 INFO - 'LOGNAME': 'cltbld', 12:24:50 INFO - 'MAIL': '/var/mail/cltbld', 12:24:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:24:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:50 INFO - 'MOZ_NO_REMOTE': '1', 12:24:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:24:50 INFO - 'NO_EM_RESTART': '1', 12:24:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:50 INFO - 'PWD': '/builds/slave/test', 12:24:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:24:50 INFO - 'SHELL': '/bin/bash', 12:24:50 INFO - 'SHLVL': '1', 12:24:50 INFO - 'TERM': 'linux', 12:24:50 INFO - 'TMOUT': '86400', 12:24:50 INFO - 'USER': 'cltbld', 12:24:50 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901', 12:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:50 INFO - '_': '/tools/buildbot/bin/python'} 12:24:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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:24:50 INFO - mozversion application_buildid: 20160920092516 12:24:50 INFO - mozversion application_changeset: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 12:24:50 INFO - mozversion application_display_name: Firefox 12:24:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:24:50 INFO - mozversion application_name: Firefox 12:24:50 INFO - mozversion application_remotingname: firefox 12:24:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:24:50 INFO - mozversion application_vendor: Mozilla 12:24:50 INFO - mozversion application_version: 50.0 12:24:50 INFO - mozversion platform_buildid: 20160920092516 12:24:50 INFO - mozversion platform_changeset: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 12:24:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:24:50 INFO - mozversion platform_version: 50.0 12:24:50 INFO - using testdate: 1474399490 12:24:50 INFO - actual date: 1474399490 12:24:50 INFO - starting webserver on 'localhost:45745' 12:24:50 INFO - SUITE-START | Running 2 tests 12:24:50 INFO - TEST-START | dromaeo_css 12:24:50 INFO - operating with platform_type : linux_ 12:24:50 INFO - Initialising browser for dromaeo_css test... 12:24:50 INFO - TEST-INFO | started process 16613 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9DcmA1/profile http://localhost:45745/getInfo.html) 12:24:55 INFO - PROCESS | 16613 | __metrics Screen width/height:1600/1200 12:24:55 INFO - PROCESS | 16613 | colorDepth:24 12:24:55 INFO - PROCESS | 16613 | Browser inner width/height: 1024/697 12:24:55 INFO - PROCESS | 16613 | __metrics 12:24:56 INFO - PROCESS | 16613 | 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:24:56 INFO - PROCESS | 16613 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:24:56 INFO - TEST-INFO | 16613: exit 0 12:24:56 INFO - Browser initialized. 12:24:56 INFO - Running cycle 1/1 for dromaeo_css test... 12:24:56 INFO - TEST-INFO | started process 16701 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9DcmA1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:25:08 INFO - PROCESS | 16701 | RSS: Main: 144285696 12:25:08 INFO - PROCESS | 16701 | 12:25:59 INFO - PROCESS | 16701 | 1474399559413 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:28:08 INFO - PROCESS | 16701 | Cycle 1(1): loaded http://localhost:45745/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45745/tests/dromaeo/cssquery-ext.html) 12:28:09 INFO - PROCESS | 16701 | RSS: Main: 270569472 12:28:09 INFO - PROCESS | 16701 | 12:31:11 INFO - PROCESS | 16701 | Cycle 1(1): loaded http://localhost:45745/tests/dromaeo/cssquery-ext.html (next: http://localhost:45745/tests/dromaeo/cssquery-jquery.html) 12:31:12 INFO - PROCESS | 16701 | RSS: Main: 240295936 12:31:12 INFO - PROCESS | 16701 | 12:34:14 INFO - PROCESS | 16701 | Cycle 1(1): loaded http://localhost:45745/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45745/tests/dromaeo/cssquery-mootools.html) 12:34:14 INFO - PROCESS | 16701 | RSS: Main: 178225152 12:34:14 INFO - PROCESS | 16701 | 12:37:16 INFO - PROCESS | 16701 | Cycle 1(1): loaded http://localhost:45745/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45745/tests/dromaeo/cssquery-prototype.html) 12:37:17 INFO - PROCESS | 16701 | RSS: Main: 223776768 12:37:17 INFO - PROCESS | 16701 | 12:40:19 INFO - PROCESS | 16701 | Cycle 1(1): loaded http://localhost:45745/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45745/tests/dromaeo/cssquery-yui.html) 12:40:20 INFO - PROCESS | 16701 | RSS: Main: 176164864 12:40:20 INFO - PROCESS | 16701 | 12:43:22 INFO - PROCESS | 16701 | Cycle 1(1): loaded http://localhost:45745/tests/dromaeo/cssquery-yui.html (next: http://localhost:45745/tests/dromaeo/cssquery-dojo.html) 12:43:22 INFO - PROCESS | 16701 | RSS: Main: 264716288 12:43:22 INFO - PROCESS | 16701 | 12:43:22 INFO - PROCESS | 16701 | __start_tp_report 12:43:22 INFO - PROCESS | 16701 | _x_x_mozilla_page_load 12:43:22 INFO - PROCESS | 16701 | _x_x_mozilla_page_load_details 12:43:22 INFO - PROCESS | 16701 | |i|pagename|runs| 12:43:22 INFO - PROCESS | 16701 | |0;dojo.html;3192.3615276944615;3192.425363434582;3207.6792320767927;3231.8707251709934;3255.1862034491364;5557.110862262039;5632.971835140818;5649.180868774036;5685.033544297469;5700.1449782532645;5604.327480702311;5679.176519404684;5685.772569097231;5693.829185124442;5730.885382292352;4946.109700253953;4980.103581355354;5109.744512774356;5142;5208.609354298424;5003.949960500403;5038.345015148044;5058;5122.846314610568;5141.897162056756;5067.467915868836;5090.312807770959;5096.133657278253;5120.129577971754;5120.334356533645;5163.612729045337;5178.715170665612;5181.948180518184;5188.792448302102;5211.009908117446;4952.851414457572;5006.3241462402475;5035.521625445577;5090.414602320707;5094.490550944913;5539.778408863641;5554.1113421852715;5556.333240011225;5560.944390556083;5609.158626206102;5963.254593175853;5992.370801065869;6006.069059295816;6007.909881351783;6009.669468179249;5774.191613174117;5924.644521328734;5945.078512830518;5950.166976623276;6037.7584896604085;5617.859553511154;5623.0721930881355;5626.367070988252;5633.15502674598;5661.207430959586;6055.485283750842;6100.816975490742;6101.389861013863;6115.4801841843055;6116.26604807426;5694.430556944272;5770.422957704196;5803.912941305884;5838.766449341979;5946.8810623787285;5974.402559744078;5977.013792724152;5979.4917432018765;5981.431763982415;5992;5937.910931336033;5944.910826337608;5946.821595352059;5955.880882382328;5962.34414214436;5782.392848750905;5838.182654428383;5895.20414744004;5938.465538101592;5951.345352011275;4595.080983803253;4607.976960115178;4612.146752850666;4612.95387046132;4635.768211589407;4108.938365924513;4114.938275925863;4116.567760385176;4138.110306284163;4142.192272506893;5415.6479828811;5541.806036788774;5558.471945165203;5635.295588051494;5689.715514224272;5903.996320625504;5913.704314784243;5934.673592952343;5947.821565353046;5961.940380596138;5626.662400255916;5641;5643.463870932255;5646.6894320811925;5649.293838270216;5059.494050594911;5149.2276158576515;5162.819301324435;5181.689098654093;5191.662541934762;6132.110769107974;6190.597611155261;6234.314225435289;6236.75052997875;6239.095331176954;6040.365761595145;6043.09353596964;6063.818085457443;6065.171409187061;6086.60437071589;5910.290765108214;6119.807225076679;6165.84585385351;6204.658743769136;6229.3770622937345;5965.821025369246;5966.642001479925;5973.924693555202;6000.0999850022845;6010.699465026819;6027;6069.02895536712;6076.453119219292;6079.939200607937;6085.969570152298;5647.745851436613;5758.049921762862;5845.357010728731;5921.378255283305;5947.881042379215;5915.556333275108;5922.200502932049;5933.080372542263;5940.524758019435;5967.224260846062;5887.499562537232;5890.910181616415;5897.970510147422;5900.970495147669;5907.49786268172;5832.591718579659;5836.124581312837;5881.794137205177;5895.056790913433;5932.644041357532;5804.94195058044;5809.070548712186;5810.7094645266725;5810.73851676684;5830.9416905832095 12:43:22 INFO - PROCESS | 16701 | |1;ext.html;590.949769269612;634.831769581061;648.6205569741704;655.1450357283745;657.0800878769719;33876;46651.533484665146;47090.76454617722;47123.29315060277;47177;48161;48533.51466485334;48540.2718959215;48552.75723621386;48765;8468.280196183301;9342.813143737121;9498.09768072032;9498.952505237465;11048.392338421383;10769.892301076967;11252.718682032933;11257.662270131868;11260.268082574607;11261.268017578832;11388.032017278541;11396.487158077865;11418.14363922705;11423.828642570368;11439.19925605208;11309.208355415125;11347.148963827745;11347.716307092307;11358.318500889973;11366.431678416087;11208.66374008781;11264.887351126466;11275.548978040868;11284.040856527205;11285.210035297532;45329.32006019913;45689.54310456886;45694.543054569695;45705.08589828218;45832.77083614594;3548.1484443733575;3747.4004159334377;3985.740926839746;4113.835446582133;4424.203643344197;34772;35083.824580876935;35115.29769404598;35243.29513409743;35284.82357588196;4666.603338716213;4672.929906051412;4723.315119307681;4731.4795372509;4830.2513110467935;366332;366541;367469;367947;370333;67178.66410667915;67712;67727.32272677308;67828;67869.66065169642;40898.1820363591;41316.1736765263;41322.58677413248;69816;69901.30098699049;14120.788188177186;14129.364178611988;14136.50522231717;14136.505222317375;14150.29248537569;11625.24435911663;12024.338661373533;12416.565420210249;12448.875511244953;12455.377231138407;20812.167513299602;20884.37346879596;20941.162353505995;21045.684314735292;21107.788922110583;7352.5956072415465;7384.963075184697;7389.81525461857;7499.775006749806;7649.617519124022;992.4243938515643;1083.615316562609;1094.7317907112808;1138.8576427946507;1203.5907791350983;35165.64834351624;36053.45919811205;36069.819650901576;36127.4580881287;36218.456723149175;1135.437958210691;1145.828125781123;1155.1625071822862;1170.631251155883;1233.7053081268832;16761.580960475843;18061.187246573936;18072.09639518019;18090.728639070425;18126.909365453088;623425;623749;626306.8684656639;627699;628199;66236.66881665561;66466.33533664602;66581.33418665848;66618;66785.33214667888;1352.8511863694985;1430.8497607751244;1465.033078168425;1468.8678018978344;1489.255372313843;35405.46891796677;36317.54729810778;36550.156887901154;36765;36934.63065369312;58033.129503058335;58807.411925880195;58844;58915.41084589099;58919.705401474435;448487;453333;453378.73310633237;453521;454843;28307.292317692223;30812.69187308098;31669.366612667156;31823.681763182998;32397.35205295928;53541.732291338296;53823.73088134534;54400.183997239285;54430;54892.72553637206;44509;45272.77363613292;45796.54203457923;46172.30741538947;46310.7684461589;44459.55540444684;45675.77162114301;45729.771351143034;45821.77089114533;46412;43569.128617428105;44088.77955610202;44483.33275000813;44650.776746117364;45028.549714502435;37389.81305093457;40171.39742903801;40224.597754023256;40312.39531406973;40322.79838600788;29644.40711185721;29689.68741188504;29827.245140490802;30242.395152097273;30304.696953030187 12:43:22 INFO - PROCESS | 16701 | |2;jquery.html;406.8982754311422;414.2398698388457;425.7764673546388;428.48153733981883;433.7896120381614;19407.70888436673;20558.07488663012;21144.89427552862;21685.13259469623;21907.561848763056;20751.896240518814;20854.270100546484;21003.789962100374;21111;21199;5717.857053573653;5967.522598192156;5969.940300597003;5973.611715238496;5975.611585246967;5675.5459563234845;5839.357670656224;5851.268591426072;5872.236609240793;5872.383399743029;5843;5846.795362162326;5849.619774714652;5850.561207909403;5852.092925596539;5632.35227948787;5790.420957904218;5809.738561764731;5814.534837213013;5825.7087145642545;5176.715280659562;5209.218617207412;5258.921116183222;5259.815906443295;5260.447652996419;24856;25089.749102508922;25213.36966575832;25224.11715589964;25604.103856365124;2473.0355161487037;2603.869806509686;2633.3416645838543;2649.231722800386;2999.2651800308845;14730.410783568645;15021.69956600862;15602.219889005617;15706;15864;2053.640612892753;2071.461420030788;2095.758987716417;2102.0015492640873;2161.729783777028;89203;90150;91713;92289;92922.07077929201;11544.88455115455;11664.358460284766;11890.702732431586;12152.75694486123;12282.570110046105;16860.32558697663;16863.241154147847;16873.746893796604;16903.577410564834;16905.83094169067;8053.798655033554;8311.542865142475;8373.62318695648;8414.11651776567;8509.659613615386;7906.881396779053;7924.771660392648;7974.122846486882;8098.149694282016;8141.796455088552;4938.160512712848;4961.131801934647;4991.07665081961;5006.499350064964;5060.063888180698;2017.3141132015185;2046.9488262793252;2059.5366042640285;2068.079704531469;2109.458102985898;1024.2267088348399;1032.3444612670858;1044.9895501045044;1053.536443964653;1073.2272763610188;19691.803081968996;21174.15303387878;21403.357899263046;21422.357329280145;21467;1038.6883934819523;1063.0273299930632;1070.1599244592958;1090.5528733219342;1101.8512500812287;10737.194710396752;11488.597899073493;11528.423578821026;11609.419529023684;11631.185816992898;405591;407033;408784;535106;539118;73678.52642947219;99302.50348748354;99344;102366;103899;1018.1193267823428;1019.143919107938;1038.4889985071854;1054.1566746602687;1066.1470823341451;18500;18574.44276671702;18994.24023039063;19122.617547649246;19498.220071196993;42413.15173696447;42649;42944;45923.31115033344;47605.52394476011;61494;61889;63645;65608.67195664182;66195;8950.418222815497;9062.093790620886;9149.542522873697;9194.034626364135;9358.672446464341;13609.931950340186;14160.575182744535;14182.645433864236;14224.431022758974;14521.709565808413;12297;12368.628941131421;12376.381180940736;12448.31534265624;12465.875341246472;12149.817752733892;12301.692457688272;12302.138850280395;12458.875411245772;12501.74996500083;12292.569760058355;12404.689882753004;12470.189437686522;12578.496860125493;12723.745525089262;8774.517401542977;8929.73210803677;9090.909090909006;9170.037146099563;9239.676611318571;9339.299552533452;11805;12138.271703697454;12138.514459421523;12350.197237179555 12:43:22 INFO - PROCESS | 16701 | |3;mootools.html;1408.387351502096;1480.666849958759;1540.2606748760595;1649.6865595536847;1837.7978422373544;8639.43843650163;9077;9113.863292050624;9150.084991500855;9467.526623668824;9048.954755226214;9265.258779297657;9490.240780737542;9509.667161649344;9519.666811661593;2419.4556224849443;2474.418511649761;2483.31708780086;2483.726790053093;2484.6397272395493;2432.0879670123704;2510.472800711853;2513.949721005579;2515.408878913454;2516.7986561075163;2477.8513289202615;2493.9750602493923;2505.210858579551;2509.9749002509925;2531.721510633829;2443.535728211643;2463.4334103156193;2467.543504451682;2469.740677228883;2473.802095832329;2437.317551085699;2484.838485498437;2492.302155396492;2495.900163993438;2497.0760818497274;9224;9288.907110928872;9367.578458969294;9457.243420526342;9461.432314061178;5376.4085950545405;5530.861728456781;5570.080936645449;5570.136628822539;5570.693611851347;3556.5732112146447;3581;3643.344198044351;3647.1611529348397;3656.2687462507606;1204.3195594489086;1214.2289646074805;1218.1716432825674;1225.1179151011258;1230.5077968812457;13537.729245415136;13679.179249245077;13757.656058598515;13759.862401376058;13768.449262029506;4857.198562237262;4881.902361952741;4893.510648935149;4908.04293162837;5067.265246539305;11502.194846360675;11505.367204803817;11522.193446458836;11528.077753779677;11544.88455115438;5659.264295641623;5664.97167514168;5665.915011274834;5666.688332141689;5676.2337084493065;4416.867493975186;4729.597984171384;5131;5166.741662916839;5197.142471492237;4389.3635422864145;4416.668749843775;4432.911341773147;4473.910521789546;4483.955160448354;1355.5865461034282;1402.396969303189;1405.838328592225;1406.2476575032308;1415.1438379780084;989.3371441134313;1028.3932479836933;1032.6024480575034;1050.1283934334483;1058.1799105693149;6222.377762223741;6561.245456772437;6597.274299827015;6735.326467353226;6789.864202716017;460.88938654722625;484.0923268870867;489.2563303778237;490.8920037591724;495.54162399780057;4361.80371883266;4365.890852728644;4484.641228701696;4486.080353527519;4505.414296141481;11890.167688261912;12192.39038048094;12312.076594255466;12335;12342.444589993473;11275.097992160607;11727.120465965087;11750.882491174978;12208;12256.325902075472;8704.086070962585;9116.498592577473;9128.49793261377;9143.817123657489;9144.405613635092;6461.224653041665;6684.866302674017;6847.828804279933;6894.620795856276;6990;10907.83638245411;11235.66293011211;11277.379744114152;11367.829482557934;11496;10086.798264034825;10811.945940270249;11500.597479088192;11612;11693.064554835762;8731.781705457415;9234.630614775333;9234.907650923673;9284.72145835626;9354.111359420844;9196.08039196075;9335.299852510952;9340.71977840666;9345;9403.576839042124;8336.374771892253;8375.659894417107;8418.074011858569;8521.53131577769;8521.701740439055;8133.959330203311;8252.628631711708;8305.335573154254;8335.458195217292;8356.582170891552;7780.183080776437;7804.297613214838;7806.453548251568;7869.3311068559815;7910.129885712682;5562.66624002545;5616.803411880564;5618.915716264341;5648.519875810438;5836.854078648068;5723.370429252862;5769.59612827098;5780.30636323627;5787.884242315215;5872.471477567039 12:43:22 INFO - PROCESS | 16701 | |4;prototype.html;346.164013906416;355.90924314299866;356.0990693545331;356.59170250063676;360.70782666040515;5432.266644003058;5495.642783219088;5498.615096943216;5502;5517.393086760453;5141.845744627667;5171.922421163686;5293.602979776513;5331.013762453939;5450.345958484977;4248.787560621972;4255.36169574564;4262;4284.892877678052;4286.399904013425;4276.187524370375;4290;4290.828366865321;4294.763787991659;4322.335900678246;4098.672106231509;4207.200631879933;4224.112936283384;4244.596763307481;4259.659227261827;4232.386303985935;4252.213340532012;4255.1064276501975;4260.659147268211;4262.744235345889;3804.2201348723447;3813.923721525554;3817.2556351511466;3818.198178382543;3822.7515211511163;5015.523525265094;5231.032259032091;5231.163013917793;5264.842054738326;5280.630355875091;4813;4835.153848076608;4838.733869637169;4850.854474365739;4861.805527778884;5612.550995920316;5614.466625670555;5634.267545219136;5650.74571644277;5659.8585035374035;4058.7767672777986;4100.466939297902;4125.484314460692;4128.422020917074;4152.065785198336;5752.654840709571;5786.132080187964;5786.826395208151;5796;5798.391168927286;4166.6250037496775;4180.91638167241;4186.5604111568455;4192.979035104805;4231.936520952188;4110.753354798721;4226.36604509326;4275.593818587229;4276.994906197041;4283.87148385549;4803.879903002383;4806.7836947337455;4807.4471435784635;4813.855584332476;4840.394950631171;5656.971715141398;5667.518260947783;5706.600537962303;5744.885102297931;5746.655200687972;1444.2995147353524;1463.9268036598128;1472.4625511688157;1482.6664000599778;1483.7922690823293;930.8324501589643;939.234523863063;949.662869681267;957.8802649668792;967.5162418790604;4878.829240976549;4889.408613434885;4901.754912254373;4903.289023091632;4923;5121.258772017532;5161.735374833115;5180.326557547494;5184.585233181337;5188.299579556787;4853.199222128309;4864.902701945941;4896.2410826321975;4898.338724272177;5106.795728170906;3035.8330291833718;3066.3254084101463;3070.3398769264268;3071.508558630653;3071.769617278713;5965.284165900033;5977.462028417463;5981.6710080945095;5987;5993.790217342459;5618.747156377973;5835.708214589254;5849.88300233993;5865.677387743715;5892.705364731746;5982.880342393128;5992.790252341234;5994.400559943971;5995.940040599538;6014.18808460861;5640.887182256414;5828.417158284307;5830.737616807169;5878.853623543368;5892;5740.51205647508;5740.913886291625;5779.335376431764;5791.799116335766;5815.45890339052;5989.850253743692;5991.400859913973;5993.520518358608;6069;6081.027035674448;4101.569335219879;4185.204811085884;4194.39181318701;4234.407182994321;4260.786960652017;5598.748056337393;5632.408597097246;5783.19035335045;5820.243368362255;5859.121131830259;5639.2668953035845;5656.462636049569;5660.801871934463;5669.688167150846;5690.2887139107615;5492.890142197213;5638;5643.971780141073;5685;5688.829335119953;5513.283273174622;5599.160125981135;5622.859428514156;5637.661740295595;5641.464060914206;5653.745581448927;5706.058500347396;5708.686022268649;5739.684317362585;5756.58963553932;5364.329458817648;5555.50000499957;5565.916511252415;5577.776888924398;5607.859803504945 12:43:22 INFO - PROCESS | 16701 | |5;yui.html;791.4143533785001;792.520525082325;802.4703695560927;825.5005721538469;900.0594378874076;15689;15734;15934.92032539837;16700.83299167008;16752.246148923314;14766.409343626243;14978.475753348635;14989.025713328627;15007.099574025538;15014.324355404005;714.885618301072;827.3505298340804;835.8411901738665;846.8645016797313;858.4548811504677;843.0768308701993;847.2501835875264;852.6802449081595;853.6670698427619;853.8164294676643;904.2539904578716;905.7373361725092;906.1572737354257;906.650939388337;906.8095699902996;885.7209978856672;891.8662200669886;892.5894088719165;893.1738142218487;893.5889490834224;664.8072059102855;664.9767258145941;666.2005593288026;666.8466252761843;666.9032990216391;14246.501372451914;14304.499342522962;14395.424183032666;14404.711905761826;14430.567082987527;15857.524274271675;15879.126648034353;15986.200689965455;16072.517824465285;16124.274407651685;13076.476940922352;13194.200373243275;13265.535706250332;13333;13394.330283485786;3999.8800035998966;4102.364133559303;4110.095778928631;4238.554951730086;4241.724287921275;81923.18076819215;82297.17702822955;82329;82681.58659206725;83086.58456707677;10687.732806679895;10705.9464702676;11138.94430527842;11362.375069371099;11366.772664546663;24114;24122.51754964926;24148.63777043346;24182.516349672907;24190.758092419204;8598.5700714964;8656.350773691998;8734.563271836383;8955.194032536972;9117.954410227907;4695.624350051988;4836.758162091881;4884.731339776346;5005.72468514228;5106.336176297058;3701.981490092532;3856;3857.3828187489867;3859.7684138951754;3868.4777555030273;715.6135686729212;746.3730466408243;749.9775006749807;752.834376437183;757.7196437318228;685.4927353758183;721.859237448703;770.2374649097466;778.9707662690732;785.2696991797683;10068.597256109673;10310;10437.530311136017;10614.203934704929;10961.945190274157;719.7624783821326;732.3226015935238;743.6096049573908;760.4182800157884;770.4991755359061;5007.173816320339;5053.498518233425;5142.97428512855;5149.871253218625;5161.4322424533275;67087.66456167688;68974;71727.28272717206;122297.38851305864;124619;108089.45955270331;110252.4487377574;111844;112847.43576282066;113100;62967.99999999908;94521.52739236398;99400;99927.50036249918;100715.49642251742;9518.286128540249;9536.713898583053;9581.568829402833;9740.707778766648;9836.114749672564;56019;57142;57670.42329576818;59467.40532594792;59795;73572;79030;82529.58735206285;85312;86219.5689021576;11912.940435297767;11963;12043.156979011736;12097.395130243276;12170.513179472722;13566.93216533911;13851.099678520864;13964.092333998258;14150.929245353707;14172.149671019772;12540.686482838002;13111.737765244832;13132.474701011852;13248.403821827847;13388.397522111723;12567.4128942937;12797.29614871191;12920.676983075498;12960.944775794129;13605.115667481581;12549.498020079094;13047.478100876238;13055.412506437044;13349.866501334862;13667.521636742666;10599.364038157735;10649.733756656145;10713.625023124154;10782.67651970442;10898.18263630215;10843.349399036084;11070.889291107307;11145.83281250798;11381.203315767816;11436 12:43:22 INFO - PROCESS | 16701 | __end_tp_report 12:43:22 INFO - PROCESS | 16701 | __start_cc_report 12:43:22 INFO - PROCESS | 16701 | _x_x_mozilla_cycle_collect,1093 12:43:22 INFO - PROCESS | 16701 | __end_cc_report 12:43:22 INFO - PROCESS | 16701 | __startTimestamp1474400602834__endTimestamp 12:43:22 INFO - PROCESS | 16701 | 12:43:22 INFO - PROCESS | 16701 | ------- Summary: start ------- 12:43:22 INFO - PROCESS | 16701 | Number of tests: 6 12:43:22 INFO - PROCESS | 16701 | 12:43:22 INFO - PROCESS | 16701 | [#0] dojo.html Cycles:165 Average:5565.62 Median:5804.94 stddev:629.46 (10.8%) stddev-sans-first:603.23 12:43:22 INFO - PROCESS | 16701 | Values: 3192.4 3192.4 3207.7 3231.9 3255.2 5557.1 5633.0 5649.2 5685.0 5700.1 5604.3 5679.2 5685.8 5693.8 5730.9 4946.1 4980.1 5109.7 5142.0 5208.6 5003.9 5038.3 5058.0 5122.8 5141.9 5067.5 5090.3 5096.1 5120.1 5120.3 5163.6 5178.7 5181.9 5188.8 5211.0 4952.9 5006.3 5035.5 5090.4 5094.5 5539.8 5554.1 5556.3 5560.9 5609.2 5963.3 5992.4 6006.1 6007.9 6009.7 5774.2 5924.6 5945.1 5950.2 6037.8 5617.9 5623.1 5626.4 5633.2 5661.2 6055.5 6100.8 6101.4 6115.5 6116.3 5694.4 5770.4 5803.9 5838.8 5946.9 5974.4 5977.0 5979.5 5981.4 5992.0 5937.9 5944.9 5946.8 5955.9 5962.3 5782.4 5838.2 5895.2 5938.5 5951.3 4595.1 4608.0 4612.1 4613.0 4635.8 4108.9 4114.9 4116.6 4138.1 4142.2 5415.6 5541.8 5558.5 5635.3 5689.7 5904.0 5913.7 5934.7 5947.8 5961.9 5626.7 5641.0 5643.5 5646.7 5649.3 5059.5 5149.2 5162.8 5181.7 5191.7 6132.1 6190.6 6234.3 6236.8 6239.1 6040.4 6043.1 6063.8 6065.2 6086.6 5910.3 6119.8 6165.8 6204.7 6229.4 5965.8 5966.6 5973.9 6000.1 6010.7 6027.0 6069.0 6076.5 6079.9 6086.0 5647.7 5758.0 5845.4 5921.4 5947.9 5915.6 5922.2 5933.1 5940.5 5967.2 5887.5 5890.9 5898.0 5901.0 5907.5 5832.6 5836.1 5881.8 5895.1 5932.6 5804.9 5809.1 5810.7 5810.7 5830.9 12:43:22 INFO - PROCESS | 16701 | 12:43:22 INFO - PROCESS | 16701 | [#1] ext.html Cycles:180 Average:65795.37 Median:40198.00 stddev:131182.21 (326.3%) stddev-sans-first:131458.86 12:43:22 INFO - PROCESS | 16701 | Values: 590.9 634.8 648.6 655.1 657.1 33876.0 46651.5 47090.8 47123.3 47177.0 48161.0 48533.5 48540.3 48552.8 48765.0 8468.3 9342.8 9498.1 9499.0 11048.4 10769.9 11252.7 11257.7 11260.3 11261.3 11388.0 11396.5 11418.1 11423.8 11439.2 11309.2 11347.1 11347.7 11358.3 11366.4 11208.7 11264.9 11275.5 11284.0 11285.2 45329.3 45689.5 45694.5 45705.1 45832.8 3548.1 3747.4 3985.7 4113.8 4424.2 34772.0 35083.8 35115.3 35243.3 35284.8 4666.6 4672.9 4723.3 4731.5 4830.3 366332.0 366541.0 367469.0 367947.0 370333.0 67178.7 67712.0 67727.3 67828.0 67869.7 40898.2 41316.2 41322.6 69816.0 69901.3 14120.8 14129.4 14136.5 14136.5 14150.3 11625.2 12024.3 12416.6 12448.9 12455.4 20812.2 20884.4 20941.2 21045.7 21107.8 7352.6 7385.0 7389.8 7499.8 7649.6 992.4 1083.6 1094.7 1138.9 1203.6 35165.6 36053.5 36069.8 36127.5 36218.5 1135.4 1145.8 1155.2 1170.6 1233.7 16761.6 18061.2 18072.1 18090.7 18126.9 623425.0 623749.0 626306.9 627699.0 628199.0 66236.7 66466.3 66581.3 66618.0 66785.3 1352.9 1430.8 1465.0 1468.9 1489.3 35405.5 36317.5 36550.2 36765.0 36934.6 58033.1 58807.4 58844.0 58915.4 58919.7 448487.0 453333.0 453378.7 453521.0 454843.0 28307.3 30812.7 31669.4 31823.7 32397.4 53541.7 53823.7 54400.2 54430.0 54892.7 44509.0 45272.8 45796.5 46172.3 46310.8 44459.6 45675.8 45729.8 45821.8 46412.0 43569.1 44088.8 44483.3 44650.8 45028.5 37389.8 40171.4 40224.6 40312.4 40322.8 29644.4 29689.7 29827.2 30242.4 30304.7 12:43:22 INFO - PROCESS | 16701 | 12:43:22 INFO - PROCESS | 16701 | [#2] jquery.html Cycles:180 Average:29396.77 Median:12190.30 stddev:76918.87 (631.0%) stddev-sans-first:77103.85 12:43:22 INFO - PROCESS | 16701 | Values: 406.9 414.2 425.8 428.5 433.8 19407.7 20558.1 21144.9 21685.1 21907.6 20751.9 20854.3 21003.8 21111.0 21199.0 5717.9 5967.5 5969.9 5973.6 5975.6 5675.5 5839.4 5851.3 5872.2 5872.4 5843.0 5846.8 5849.6 5850.6 5852.1 5632.4 5790.4 5809.7 5814.5 5825.7 5176.7 5209.2 5258.9 5259.8 5260.4 24856.0 25089.7 25213.4 25224.1 25604.1 2473.0 2603.9 2633.3 2649.2 2999.3 14730.4 15021.7 15602.2 15706.0 15864.0 2053.6 2071.5 2095.8 2102.0 2161.7 89203.0 90150.0 91713.0 92289.0 92922.1 11544.9 11664.4 11890.7 12152.8 12282.6 16860.3 16863.2 16873.7 16903.6 16905.8 8053.8 8311.5 8373.6 8414.1 8509.7 7906.9 7924.8 7974.1 8098.1 8141.8 4938.2 4961.1 4991.1 5006.5 5060.1 2017.3 2046.9 2059.5 2068.1 2109.5 1024.2 1032.3 1045.0 1053.5 1073.2 19691.8 21174.2 21403.4 21422.4 21467.0 1038.7 1063.0 1070.2 1090.6 1101.9 10737.2 11488.6 11528.4 11609.4 11631.2 405591.0 407033.0 408784.0 535106.0 539118.0 73678.5 99302.5 99344.0 102366.0 103899.0 1018.1 1019.1 1038.5 1054.2 1066.1 18500.0 18574.4 18994.2 19122.6 19498.2 42413.2 42649.0 42944.0 45923.3 47605.5 61494.0 61889.0 63645.0 65608.7 66195.0 8950.4 9062.1 9149.5 9194.0 9358.7 13609.9 14160.6 14182.6 14224.4 14521.7 12297.0 12368.6 12376.4 12448.3 12465.9 12149.8 12301.7 12302.1 12458.9 12501.7 12292.6 12404.7 12470.2 12578.5 12723.7 8774.5 8929.7 9090.9 9170.0 9239.7 9339.3 11805.0 12138.3 12138.5 12350.2 12:43:22 INFO - PROCESS | 16701 | 12:43:22 INFO - PROCESS | 16701 | [#3] mootools.html Cycles:180 Average:6334.61 Median:2595.24 stddev:3682.26 (141.9%) stddev-sans-first:3673.98 12:43:22 INFO - PROCESS | 16701 | Values: 1408.4 1480.7 1540.3 1649.7 1837.8 8639.4 9077.0 9113.9 9150.1 9467.5 9049.0 9265.3 9490.2 9509.7 9519.7 2419.5 2474.4 2483.3 2483.7 2484.6 2432.1 2510.5 2513.9 2515.4 2516.8 2477.9 2494.0 2505.2 2510.0 2531.7 2443.5 2463.4 2467.5 2469.7 2473.8 2437.3 2484.8 2492.3 2495.9 2497.1 9224.0 9288.9 9367.6 9457.2 9461.4 5376.4 5530.9 5570.1 5570.1 5570.7 3556.6 3581.0 3643.3 3647.2 3656.3 1204.3 1214.2 1218.2 1225.1 1230.5 13537.7 13679.2 13757.7 13759.9 13768.4 4857.2 4881.9 4893.5 4908.0 5067.3 11502.2 11505.4 11522.2 11528.1 11544.9 5659.3 5665.0 5665.9 5666.7 5676.2 4416.9 4729.6 5131.0 5166.7 5197.1 4389.4 4416.7 4432.9 4473.9 4484.0 1355.6 1402.4 1405.8 1406.2 1415.1 989.3 1028.4 1032.6 1050.1 1058.2 6222.4 6561.2 6597.3 6735.3 6789.9 460.9 484.1 489.3 490.9 495.5 4361.8 4365.9 4484.6 4486.1 4505.4 11890.2 12192.4 12312.1 12335.0 12342.4 11275.1 11727.1 11750.9 12208.0 12256.3 8704.1 9116.5 9128.5 9143.8 9144.4 6461.2 6684.9 6847.8 6894.6 6990.0 10907.8 11235.7 11277.4 11367.8 11496.0 10086.8 10811.9 11500.6 11612.0 11693.1 8731.8 9234.6 9234.9 9284.7 9354.1 9196.1 9335.3 9340.7 9345.0 9403.6 8336.4 8375.7 8418.1 8521.5 8521.7 8134.0 8252.6 8305.3 8335.5 8356.6 7780.2 7804.3 7806.5 7869.3 7910.1 5562.7 5616.8 5618.9 5648.5 5836.9 5723.4 5769.6 5780.3 5787.9 5872.5 12:43:22 INFO - PROCESS | 16701 | 12:43:22 INFO - PROCESS | 16701 | [#4] prototype.html Cycles:180 Average:4734.23 Median:5272.74 stddev:1373.07 (26.0%) stddev-sans-first:1336.84 12:43:22 INFO - PROCESS | 16701 | Values: 346.2 355.9 356.1 356.6 360.7 5432.3 5495.6 5498.6 5502.0 5517.4 5141.8 5171.9 5293.6 5331.0 5450.3 4248.8 4255.4 4262.0 4284.9 4286.4 4276.2 4290.0 4290.8 4294.8 4322.3 4098.7 4207.2 4224.1 4244.6 4259.7 4232.4 4252.2 4255.1 4260.7 4262.7 3804.2 3813.9 3817.3 3818.2 3822.8 5015.5 5231.0 5231.2 5264.8 5280.6 4813.0 4835.2 4838.7 4850.9 4861.8 5612.6 5614.5 5634.3 5650.7 5659.9 4058.8 4100.5 4125.5 4128.4 4152.1 5752.7 5786.1 5786.8 5796.0 5798.4 4166.6 4180.9 4186.6 4193.0 4231.9 4110.8 4226.4 4275.6 4277.0 4283.9 4803.9 4806.8 4807.4 4813.9 4840.4 5657.0 5667.5 5706.6 5744.9 5746.7 1444.3 1463.9 1472.5 1482.7 1483.8 930.8 939.2 949.7 957.9 967.5 4878.8 4889.4 4901.8 4903.3 4923.0 5121.3 5161.7 5180.3 5184.6 5188.3 4853.2 4864.9 4896.2 4898.3 5106.8 3035.8 3066.3 3070.3 3071.5 3071.8 5965.3 5977.5 5981.7 5987.0 5993.8 5618.7 5835.7 5849.9 5865.7 5892.7 5982.9 5992.8 5994.4 5995.9 6014.2 5640.9 5828.4 5830.7 5878.9 5892.0 5740.5 5740.9 5779.3 5791.8 5815.5 5989.9 5991.4 5993.5 6069.0 6081.0 4101.6 4185.2 4194.4 4234.4 4260.8 5598.7 5632.4 5783.2 5820.2 5859.1 5639.3 5656.5 5660.8 5669.7 5690.3 5492.9 5638.0 5644.0 5685.0 5688.8 5513.3 5599.2 5622.9 5637.7 5641.5 5653.7 5706.1 5708.7 5739.7 5756.6 5364.3 5555.5 5565.9 5577.8 5607.9 12:43:22 INFO - PROCESS | 16701 | 12:43:22 INFO - PROCESS | 16701 | [#5] yui.html Cycles:180 Average:21293.64 Median:4174.33 stddev:30683.12 (735.0%) stddev-sans-first:30730.58 12:43:22 INFO - PROCESS | 16701 | Values: 791.4 792.5 802.5 825.5 900.1 15689.0 15734.0 15934.9 16700.8 16752.2 14766.4 14978.5 14989.0 15007.1 15014.3 714.9 827.4 835.8 846.9 858.5 843.1 847.3 852.7 853.7 853.8 904.3 905.7 906.2 906.7 906.8 885.7 891.9 892.6 893.2 893.6 664.8 665.0 666.2 666.8 666.9 14246.5 14304.5 14395.4 14404.7 14430.6 15857.5 15879.1 15986.2 16072.5 16124.3 13076.5 13194.2 13265.5 13333.0 13394.3 3999.9 4102.4 4110.1 4238.6 4241.7 81923.2 82297.2 82329.0 82681.6 83086.6 10687.7 10705.9 11138.9 11362.4 11366.8 24114.0 24122.5 24148.6 24182.5 24190.8 8598.6 8656.4 8734.6 8955.2 9118.0 4695.6 4836.8 4884.7 5005.7 5106.3 3702.0 3856.0 3857.4 3859.8 3868.5 715.6 746.4 750.0 752.8 757.7 685.5 721.9 770.2 779.0 785.3 10068.6 10310.0 10437.5 10614.2 10961.9 719.8 732.3 743.6 760.4 770.5 5007.2 5053.5 5143.0 5149.9 5161.4 67087.7 68974.0 71727.3 122297.4 124619.0 108089.5 110252.4 111844.0 112847.4 113100.0 62968.0 94521.5 99400.0 99927.5 100715.5 9518.3 9536.7 9581.6 9740.7 9836.1 56019.0 57142.0 57670.4 59467.4 59795.0 73572.0 79030.0 82529.6 85312.0 86219.6 11912.9 11963.0 12043.2 12097.4 12170.5 13566.9 13851.1 13964.1 14150.9 14172.1 12540.7 13111.7 13132.5 13248.4 13388.4 12567.4 12797.3 12920.7 12960.9 13605.1 12549.5 13047.5 13055.4 13349.9 13667.5 10599.4 10649.7 10713.6 10782.7 10898.2 10843.3 11070.9 11145.8 11381.2 11436.0 12:43:22 INFO - PROCESS | 16701 | -------- Summary: end -------- 12:43:22 INFO - PROCESS | 16701 | 12:43:23 INFO - TEST-INFO | 16701: exit 0 12:43:23 INFO - TEST-OK | dromaeo_css | took 1112760ms 12:43:23 INFO - TEST-START | kraken 12:43:23 INFO - operating with platform_type : linux_ 12:43:23 INFO - Initialising browser for kraken test... 12:43:23 INFO - TEST-INFO | started process 16895 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKhOLqa/profile http://localhost:45745/getInfo.html) 12:43:27 INFO - PROCESS | 16895 | __metrics Screen width/height:1600/1200 12:43:27 INFO - PROCESS | 16895 | colorDepth:24 12:43:27 INFO - PROCESS | 16895 | Browser inner width/height: 1024/697 12:43:27 INFO - PROCESS | 16895 | __metrics 12:43:28 INFO - PROCESS | 16895 | 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:43:28 INFO - PROCESS | 16895 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:43:29 INFO - PROCESS | 16895 | console.error: PushService: 12:43:29 INFO - PROCESS | 16895 | stateChangeProcessEnqueue: Error transitioning state 12:43:29 INFO - PROCESS | 16895 | UnknownError 12:43:29 INFO - TEST-INFO | 16895: exit 0 12:43:29 INFO - Browser initialized. 12:43:29 INFO - Running cycle 1/1 for kraken test... 12:43:29 INFO - TEST-INFO | started process 16983 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKhOLqa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:43:41 INFO - PROCESS | 16983 | RSS: Main: 143609856 12:43:41 INFO - PROCESS | 16983 | 12:43:44 INFO - PROCESS | 16983 | Cycle 1(1): loaded http://localhost:45745/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45745/tests/kraken/driver.html?testName=audio-beat-detection) 12:43:44 INFO - PROCESS | 16983 | RSS: Main: 198799360 12:43:44 INFO - PROCESS | 16983 | 12:43:47 INFO - PROCESS | 16983 | Cycle 1(1): loaded http://localhost:45745/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45745/tests/kraken/driver.html?testName=audio-dft) 12:43:48 INFO - PROCESS | 16983 | RSS: Main: 183369728 12:43:48 INFO - PROCESS | 16983 | 12:43:50 INFO - PROCESS | 16983 | Cycle 1(1): loaded http://localhost:45745/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45745/tests/kraken/driver.html?testName=audio-fft) 12:43:51 INFO - PROCESS | 16983 | RSS: Main: 274558976 12:43:51 INFO - PROCESS | 16983 | 12:43:53 INFO - PROCESS | 16983 | Cycle 1(1): loaded http://localhost:45745/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45745/tests/kraken/driver.html?testName=audio-oscillator) 12:43:54 INFO - PROCESS | 16983 | RSS: Main: 180490240 12:43:54 INFO - PROCESS | 16983 | 12:43:56 INFO - PROCESS | 16983 | Cycle 1(1): loaded http://localhost:45745/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45745/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:43:56 INFO - PROCESS | 16983 | RSS: Main: 178384896 12:43:56 INFO - PROCESS | 16983 | 12:43:59 INFO - PROCESS | 16983 | Cycle 1(1): loaded http://localhost:45745/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45745/tests/kraken/driver.html?testName=imaging-darkroom) 12:44:00 INFO - PROCESS | 16983 | RSS: Main: 214003712 12:44:00 INFO - PROCESS | 16983 | 12:44:03 INFO - PROCESS | 16983 | Cycle 1(1): loaded http://localhost:45745/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45745/tests/kraken/driver.html?testName=imaging-desaturate) 12:44:04 INFO - PROCESS | 16983 | RSS: Main: 213450752 12:44:04 INFO - PROCESS | 16983 | 12:44:07 INFO - PROCESS | 16983 | Cycle 1(1): loaded http://localhost:45745/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45745/tests/kraken/driver.html?testName=json-parse-financial) 12:44:07 INFO - PROCESS | 16983 | RSS: Main: 213553152 12:44:07 INFO - PROCESS | 16983 | 12:44:09 INFO - PROCESS | 16983 | Cycle 1(1): loaded http://localhost:45745/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45745/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:44:09 INFO - PROCESS | 16983 | RSS: Main: 175316992 12:44:09 INFO - PROCESS | 16983 | 12:44:11 INFO - PROCESS | 16983 | Cycle 1(1): loaded http://localhost:45745/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45745/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:44:12 INFO - PROCESS | 16983 | RSS: Main: 182497280 12:44:12 INFO - PROCESS | 16983 | 12:44:14 INFO - PROCESS | 16983 | Cycle 1(1): loaded http://localhost:45745/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45745/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:44:14 INFO - PROCESS | 16983 | RSS: Main: 184922112 12:44:14 INFO - PROCESS | 16983 | 12:44:17 INFO - PROCESS | 16983 | Cycle 1(1): loaded http://localhost:45745/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45745/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:44:17 INFO - PROCESS | 16983 | RSS: Main: 185753600 12:44:17 INFO - PROCESS | 16983 | 12:44:20 INFO - PROCESS | 16983 | Cycle 1(1): loaded http://localhost:45745/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45745/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:44:21 INFO - PROCESS | 16983 | RSS: Main: 183197696 12:44:21 INFO - PROCESS | 16983 | 12:44:22 INFO - PROCESS | 16983 | Cycle 1(1): loaded http://localhost:45745/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45745/tests/kraken/driver.html?testName=ai-astar) 12:44:22 INFO - PROCESS | 16983 | RSS: Main: 234684416 12:44:22 INFO - PROCESS | 16983 | 12:44:22 INFO - PROCESS | 16983 | __start_tp_report 12:44:22 INFO - PROCESS | 16983 | _x_x_mozilla_page_load 12:44:22 INFO - PROCESS | 16983 | _x_x_mozilla_page_load_details 12:44:22 INFO - PROCESS | 16983 | |i|pagename|runs| 12:44:22 INFO - PROCESS | 16983 | |0;ai-astar;110;110;120;109;109;110;109;110;110;109 12:44:22 INFO - PROCESS | 16983 | |1;audio-beat-detection;147;121;144;180;116;144;147;119;184;115 12:44:22 INFO - PROCESS | 16983 | |2;audio-dft;161;160;162;161;161;163;159;161;158;161 12:44:22 INFO - PROCESS | 16983 | |3;audio-fft;74;75;75;177;74;75;75;74;159;75 12:44:22 INFO - PROCESS | 16983 | |4;audio-oscillator;84;84;154;84;84;84;140;84;83;140 12:44:22 INFO - PROCESS | 16983 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;117;116 12:44:22 INFO - PROCESS | 16983 | |6;imaging-darkroom;146;146;147;147;146;145;146;146;145;145 12:44:22 INFO - PROCESS | 16983 | |7;imaging-desaturate;94;95;94;95;94;94;94;94;95;95 12:44:22 INFO - PROCESS | 16983 | |8;json-parse-financial;56;51;52;52;51;52;52;50;53;52 12:44:22 INFO - PROCESS | 16983 | |9;json-stringify-tinderbox;59;59;59;59;60;59;60;60;60;60 12:44:22 INFO - PROCESS | 16983 | |10;stanford-crypto-aes;72;73;72;81;77;72;73;72;81;72 12:44:22 INFO - PROCESS | 16983 | |11;stanford-crypto-ccm;122;118;119;131;116;124;116;123;184;127 12:44:22 INFO - PROCESS | 16983 | |12;stanford-crypto-pbkdf2;153;151;155;150;153;150;149;152;150;159 12:44:22 INFO - PROCESS | 16983 | |13;stanford-crypto-sha256-iterative;62;60;60;61;60;64;60;61;60;60 12:44:22 INFO - PROCESS | 16983 | __end_tp_report 12:44:22 INFO - PROCESS | 16983 | __start_cc_report 12:44:22 INFO - PROCESS | 16983 | _x_x_mozilla_cycle_collect,1644 12:44:22 INFO - PROCESS | 16983 | __end_cc_report 12:44:22 INFO - PROCESS | 16983 | __startTimestamp1474400662990__endTimestamp 12:44:22 INFO - PROCESS | 16983 | 12:44:23 INFO - PROCESS | 16983 | ------- Summary: start ------- 12:44:23 INFO - PROCESS | 16983 | Number of tests: 14 12:44:23 INFO - PROCESS | 16983 | 12:44:23 INFO - PROCESS | 16983 | [#0] ai-astar Cycles:10 Average:110.60 Median:110.00 stddev:3.34 (3.0%) stddev-sans-first:3.54 12:44:23 INFO - PROCESS | 16983 | Values: 110.0 110.0 120.0 109.0 109.0 110.0 109.0 110.0 110.0 109.0 12:44:23 INFO - PROCESS | 16983 | 12:44:23 INFO - PROCESS | 16983 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:145.50 stddev:25.03 (17.2%) stddev-sans-first:26.48 12:44:23 INFO - PROCESS | 16983 | Values: 147.0 121.0 144.0 180.0 116.0 144.0 147.0 119.0 184.0 115.0 12:44:23 INFO - PROCESS | 16983 | 12:44:23 INFO - PROCESS | 16983 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.42 (0.9%) stddev-sans-first:1.50 12:44:23 INFO - PROCESS | 16983 | Values: 161.0 160.0 162.0 161.0 161.0 163.0 159.0 161.0 158.0 161.0 12:44:23 INFO - PROCESS | 16983 | 12:44:23 INFO - PROCESS | 16983 | [#3] audio-fft Cycles:10 Average:93.30 Median:75.00 stddev:39.60 (52.8%) stddev-sans-first:41.38 12:44:23 INFO - PROCESS | 16983 | Values: 74.0 75.0 75.0 177.0 74.0 75.0 75.0 74.0 159.0 75.0 12:44:23 INFO - PROCESS | 16983 | 12:44:23 INFO - PROCESS | 16983 | [#4] audio-oscillator Cycles:10 Average:102.10 Median:84.00 stddev:29.62 (35.3%) stddev-sans-first:30.69 12:44:23 INFO - PROCESS | 16983 | Values: 84.0 84.0 154.0 84.0 84.0 84.0 140.0 84.0 83.0 140.0 12:44:23 INFO - PROCESS | 16983 | 12:44:23 INFO - PROCESS | 16983 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 12:44:23 INFO - PROCESS | 16983 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 12:44:23 INFO - PROCESS | 16983 | 12:44:23 INFO - PROCESS | 16983 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 12:44:23 INFO - PROCESS | 16983 | Values: 146.0 146.0 147.0 147.0 146.0 145.0 146.0 146.0 145.0 145.0 12:44:23 INFO - PROCESS | 16983 | 12:44:23 INFO - PROCESS | 16983 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 12:44:23 INFO - PROCESS | 16983 | Values: 94.0 95.0 94.0 95.0 94.0 94.0 94.0 94.0 95.0 95.0 12:44:23 INFO - PROCESS | 16983 | 12:44:23 INFO - PROCESS | 16983 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:52.00 stddev:1.60 (3.1%) stddev-sans-first:0.87 12:44:23 INFO - PROCESS | 16983 | Values: 56.0 51.0 52.0 52.0 51.0 52.0 52.0 50.0 53.0 52.0 12:44:23 INFO - PROCESS | 16983 | 12:44:23 INFO - PROCESS | 16983 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 12:44:23 INFO - PROCESS | 16983 | Values: 59.0 59.0 59.0 59.0 60.0 59.0 60.0 60.0 60.0 60.0 12:44:23 INFO - PROCESS | 16983 | 12:44:23 INFO - PROCESS | 16983 | [#10] stanford-crypto-aes Cycles:10 Average:74.50 Median:73.00 stddev:3.75 (5.1%) stddev-sans-first:3.87 12:44:23 INFO - PROCESS | 16983 | Values: 72.0 73.0 72.0 81.0 77.0 72.0 73.0 72.0 81.0 72.0 12:44:23 INFO - PROCESS | 16983 | 12:44:23 INFO - PROCESS | 16983 | [#11] stanford-crypto-ccm Cycles:10 Average:128.00 Median:123.50 stddev:20.25 (16.4%) stddev-sans-first:21.37 12:44:23 INFO - PROCESS | 16983 | Values: 122.0 118.0 119.0 131.0 116.0 124.0 116.0 123.0 184.0 127.0 12:44:23 INFO - PROCESS | 16983 | 12:44:23 INFO - PROCESS | 16983 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.20 Median:152.50 stddev:3.01 (2.0%) stddev-sans-first:3.18 12:44:23 INFO - PROCESS | 16983 | Values: 153.0 151.0 155.0 150.0 153.0 150.0 149.0 152.0 150.0 159.0 12:44:23 INFO - PROCESS | 16983 | 12:44:23 INFO - PROCESS | 16983 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.80 Median:60.50 stddev:1.32 (2.2%) stddev-sans-first:1.32 12:44:23 INFO - PROCESS | 16983 | Values: 62.0 60.0 60.0 61.0 60.0 64.0 60.0 61.0 60.0 60.0 12:44:23 INFO - PROCESS | 16983 | -------- Summary: end -------- 12:44:23 INFO - PROCESS | 16983 | 12:44:23 INFO - TEST-INFO | 16983: exit 0 12:44:24 INFO - TEST-OK | kraken | took 60405ms 12:44:24 INFO - SUITE-END | took 1173s 12:44:24 INFO - Completed test suite (00:19:33) 12:44:24 INFO - javascript benchmark 12:44:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3192.3615276944615, 3192.425363434582, 3207.6792320767927, 3231.8707251709934, 3255.1862034491364, 5557.110862262039, 5632.971835140818, 5649.180868774036, 5685.033544297469, 5700.1449782532645, 5604.327480702311, 5679.176519404684, 5685.772569097231, 5693.829185124442, 5730.885382292352, 4946.109700253953, 4980.103581355354, 5109.744512774356, 5142.0, 5208.609354298424, 5003.949960500403, 5038.345015148044, 5058.0, 5122.846314610568, 5141.897162056756, 5067.467915868836, 5090.312807770959, 5096.133657278253, 5120.129577971754, 5120.334356533645, 5163.612729045337, 5178.715170665612, 5181.948180518184, 5188.792448302102, 5211.009908117446, 4952.851414457572, 5006.3241462402475, 5035.521625445577, 5090.414602320707, 5094.490550944913, 5539.778408863641, 5554.1113421852715, 5556.333240011225, 5560.944390556083, 5609.158626206102, 5963.254593175853, 5992.370801065869, 6006.069059295816, 6007.909881351783, 6009.669468179249, 5774.191613174117, 5924.644521328734, 5945.078512830518, 5950.166976623276, 6037.7584896604085, 5617.859553511154, 5623.0721930881355, 5626.367070988252, 5633.15502674598, 5661.207430959586, 6055.485283750842, 6100.816975490742, 6101.389861013863, 6115.4801841843055, 6116.26604807426, 5694.430556944272, 5770.422957704196, 5803.912941305884, 5838.766449341979, 5946.8810623787285, 5974.402559744078, 5977.013792724152, 5979.4917432018765, 5981.431763982415, 5992.0, 5937.910931336033, 5944.910826337608, 5946.821595352059, 5955.880882382328, 5962.34414214436, 5782.392848750905, 5838.182654428383, 5895.20414744004, 5938.465538101592, 5951.345352011275, 4595.080983803253, 4607.976960115178, 4612.146752850666, 4612.95387046132, 4635.768211589407, 4108.938365924513, 4114.938275925863, 4116.567760385176, 4138.110306284163, 4142.192272506893, 5415.6479828811, 5541.806036788774, 5558.471945165203, 5635.295588051494, 5689.715514224272, 5903.996320625504, 5913.704314784243, 5934.673592952343, 5947.821565353046, 5961.940380596138, 5626.662400255916, 5641.0, 5643.463870932255, 5646.6894320811925, 5649.293838270216, 5059.494050594911, 5149.2276158576515, 5162.819301324435, 5181.689098654093, 5191.662541934762, 6132.110769107974, 6190.597611155261, 6234.314225435289, 6236.75052997875, 6239.095331176954, 6040.365761595145, 6043.09353596964, 6063.818085457443, 6065.171409187061, 6086.60437071589, 5910.290765108214, 6119.807225076679, 6165.84585385351, 6204.658743769136, 6229.3770622937345, 5965.821025369246, 5966.642001479925, 5973.924693555202, 6000.0999850022845, 6010.699465026819, 6027.0, 6069.02895536712, 6076.453119219292, 6079.939200607937, 6085.969570152298, 5647.745851436613, 5758.049921762862, 5845.357010728731, 5921.378255283305, 5947.881042379215, 5915.556333275108, 5922.200502932049, 5933.080372542263, 5940.524758019435, 5967.224260846062, 5887.499562537232, 5890.910181616415, 5897.970510147422, 5900.970495147669, 5907.49786268172, 5832.591718579659, 5836.124581312837, 5881.794137205177, 5895.056790913433, 5932.644041357532, 5804.94195058044, 5809.070548712186, 5810.7094645266725, 5810.73851676684, 5830.9416905832095], "value": 5523.046991747144, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.949769269612, 634.831769581061, 648.6205569741704, 655.1450357283745, 657.0800878769719, 33876.0, 46651.533484665146, 47090.76454617722, 47123.29315060277, 47177.0, 48161.0, 48533.51466485334, 48540.2718959215, 48552.75723621386, 48765.0, 8468.280196183301, 9342.813143737121, 9498.09768072032, 9498.952505237465, 11048.392338421383, 10769.892301076967, 11252.718682032933, 11257.662270131868, 11260.268082574607, 11261.268017578832, 11388.032017278541, 11396.487158077865, 11418.14363922705, 11423.828642570368, 11439.19925605208, 11309.208355415125, 11347.148963827745, 11347.716307092307, 11358.318500889973, 11366.431678416087, 11208.66374008781, 11264.887351126466, 11275.548978040868, 11284.040856527205, 11285.210035297532, 45329.32006019913, 45689.54310456886, 45694.543054569695, 45705.08589828218, 45832.77083614594, 3548.1484443733575, 3747.4004159334377, 3985.740926839746, 4113.835446582133, 4424.203643344197, 34772.0, 35083.824580876935, 35115.29769404598, 35243.29513409743, 35284.82357588196, 4666.603338716213, 4672.929906051412, 4723.315119307681, 4731.4795372509, 4830.2513110467935, 366332.0, 366541.0, 367469.0, 367947.0, 370333.0, 67178.66410667915, 67712.0, 67727.32272677308, 67828.0, 67869.66065169642, 40898.1820363591, 41316.1736765263, 41322.58677413248, 69816.0, 69901.30098699049, 14120.788188177186, 14129.364178611988, 14136.50522231717, 14136.505222317375, 14150.29248537569, 11625.24435911663, 12024.338661373533, 12416.565420210249, 12448.875511244953, 12455.377231138407, 20812.167513299602, 20884.37346879596, 20941.162353505995, 21045.684314735292, 21107.788922110583, 7352.5956072415465, 7384.963075184697, 7389.81525461857, 7499.775006749806, 7649.617519124022, 992.4243938515643, 1083.615316562609, 1094.7317907112808, 1138.8576427946507, 1203.5907791350983, 35165.64834351624, 36053.45919811205, 36069.819650901576, 36127.4580881287, 36218.456723149175, 1135.437958210691, 1145.828125781123, 1155.1625071822862, 1170.631251155883, 1233.7053081268832, 16761.580960475843, 18061.187246573936, 18072.09639518019, 18090.728639070425, 18126.909365453088, 623425.0, 623749.0, 626306.8684656639, 627699.0, 628199.0, 66236.66881665561, 66466.33533664602, 66581.33418665848, 66618.0, 66785.33214667888, 1352.8511863694985, 1430.8497607751244, 1465.033078168425, 1468.8678018978344, 1489.255372313843, 35405.46891796677, 36317.54729810778, 36550.156887901154, 36765.0, 36934.63065369312, 58033.129503058335, 58807.411925880195, 58844.0, 58915.41084589099, 58919.705401474435, 448487.0, 453333.0, 453378.73310633237, 453521.0, 454843.0, 28307.292317692223, 30812.69187308098, 31669.366612667156, 31823.681763182998, 32397.35205295928, 53541.732291338296, 53823.73088134534, 54400.183997239285, 54430.0, 54892.72553637206, 44509.0, 45272.77363613292, 45796.54203457923, 46172.30741538947, 46310.7684461589, 44459.55540444684, 45675.77162114301, 45729.771351143034, 45821.77089114533, 46412.0, 43569.128617428105, 44088.77955610202, 44483.33275000813, 44650.776746117364, 45028.549714502435, 37389.81305093457, 40171.39742903801, 40224.597754023256, 40312.39531406973, 40322.79838600788, 29644.40711185721, 29689.68741188504, 29827.245140490802, 30242.395152097273, 30304.696953030187], "value": 21965.16442843735, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.8982754311422, 414.2398698388457, 425.7764673546388, 428.48153733981883, 433.7896120381614, 19407.70888436673, 20558.07488663012, 21144.89427552862, 21685.13259469623, 21907.561848763056, 20751.896240518814, 20854.270100546484, 21003.789962100374, 21111.0, 21199.0, 5717.857053573653, 5967.522598192156, 5969.940300597003, 5973.611715238496, 5975.611585246967, 5675.5459563234845, 5839.357670656224, 5851.268591426072, 5872.236609240793, 5872.383399743029, 5843.0, 5846.795362162326, 5849.619774714652, 5850.561207909403, 5852.092925596539, 5632.35227948787, 5790.420957904218, 5809.738561764731, 5814.534837213013, 5825.7087145642545, 5176.715280659562, 5209.218617207412, 5258.921116183222, 5259.815906443295, 5260.447652996419, 24856.0, 25089.749102508922, 25213.36966575832, 25224.11715589964, 25604.103856365124, 2473.0355161487037, 2603.869806509686, 2633.3416645838543, 2649.231722800386, 2999.2651800308845, 14730.410783568645, 15021.69956600862, 15602.219889005617, 15706.0, 15864.0, 2053.640612892753, 2071.461420030788, 2095.758987716417, 2102.0015492640873, 2161.729783777028, 89203.0, 90150.0, 91713.0, 92289.0, 92922.07077929201, 11544.88455115455, 11664.358460284766, 11890.702732431586, 12152.75694486123, 12282.570110046105, 16860.32558697663, 16863.241154147847, 16873.746893796604, 16903.577410564834, 16905.83094169067, 8053.798655033554, 8311.542865142475, 8373.62318695648, 8414.11651776567, 8509.659613615386, 7906.881396779053, 7924.771660392648, 7974.122846486882, 8098.149694282016, 8141.796455088552, 4938.160512712848, 4961.131801934647, 4991.07665081961, 5006.499350064964, 5060.063888180698, 2017.3141132015185, 2046.9488262793252, 2059.5366042640285, 2068.079704531469, 2109.458102985898, 1024.2267088348399, 1032.3444612670858, 1044.9895501045044, 1053.536443964653, 1073.2272763610188, 19691.803081968996, 21174.15303387878, 21403.357899263046, 21422.357329280145, 21467.0, 1038.6883934819523, 1063.0273299930632, 1070.1599244592958, 1090.5528733219342, 1101.8512500812287, 10737.194710396752, 11488.597899073493, 11528.423578821026, 11609.419529023684, 11631.185816992898, 405591.0, 407033.0, 408784.0, 535106.0, 539118.0, 73678.52642947219, 99302.50348748354, 99344.0, 102366.0, 103899.0, 1018.1193267823428, 1019.143919107938, 1038.4889985071854, 1054.1566746602687, 1066.1470823341451, 18500.0, 18574.44276671702, 18994.24023039063, 19122.617547649246, 19498.220071196993, 42413.15173696447, 42649.0, 42944.0, 45923.31115033344, 47605.52394476011, 61494.0, 61889.0, 63645.0, 65608.67195664182, 66195.0, 8950.418222815497, 9062.093790620886, 9149.542522873697, 9194.034626364135, 9358.672446464341, 13609.931950340186, 14160.575182744535, 14182.645433864236, 14224.431022758974, 14521.709565808413, 12297.0, 12368.628941131421, 12376.381180940736, 12448.31534265624, 12465.875341246472, 12149.817752733892, 12301.692457688272, 12302.138850280395, 12458.875411245772, 12501.74996500083, 12292.569760058355, 12404.689882753004, 12470.189437686522, 12578.496860125493, 12723.745525089262, 8774.517401542977, 8929.73210803677, 9090.909090909006, 9170.037146099563, 9239.676611318571, 9339.299552533452, 11805.0, 12138.271703697454, 12138.514459421523, 12350.197237179555], "value": 9823.374141266713, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1408.387351502096, 1480.666849958759, 1540.2606748760595, 1649.6865595536847, 1837.7978422373544, 8639.43843650163, 9077.0, 9113.863292050624, 9150.084991500855, 9467.526623668824, 9048.954755226214, 9265.258779297657, 9490.240780737542, 9509.667161649344, 9519.666811661593, 2419.4556224849443, 2474.418511649761, 2483.31708780086, 2483.726790053093, 2484.6397272395493, 2432.0879670123704, 2510.472800711853, 2513.949721005579, 2515.408878913454, 2516.7986561075163, 2477.8513289202615, 2493.9750602493923, 2505.210858579551, 2509.9749002509925, 2531.721510633829, 2443.535728211643, 2463.4334103156193, 2467.543504451682, 2469.740677228883, 2473.802095832329, 2437.317551085699, 2484.838485498437, 2492.302155396492, 2495.900163993438, 2497.0760818497274, 9224.0, 9288.907110928872, 9367.578458969294, 9457.243420526342, 9461.432314061178, 5376.4085950545405, 5530.861728456781, 5570.080936645449, 5570.136628822539, 5570.693611851347, 3556.5732112146447, 3581.0, 3643.344198044351, 3647.1611529348397, 3656.2687462507606, 1204.3195594489086, 1214.2289646074805, 1218.1716432825674, 1225.1179151011258, 1230.5077968812457, 13537.729245415136, 13679.179249245077, 13757.656058598515, 13759.862401376058, 13768.449262029506, 4857.198562237262, 4881.902361952741, 4893.510648935149, 4908.04293162837, 5067.265246539305, 11502.194846360675, 11505.367204803817, 11522.193446458836, 11528.077753779677, 11544.88455115438, 5659.264295641623, 5664.97167514168, 5665.915011274834, 5666.688332141689, 5676.2337084493065, 4416.867493975186, 4729.597984171384, 5131.0, 5166.741662916839, 5197.142471492237, 4389.3635422864145, 4416.668749843775, 4432.911341773147, 4473.910521789546, 4483.955160448354, 1355.5865461034282, 1402.396969303189, 1405.838328592225, 1406.2476575032308, 1415.1438379780084, 989.3371441134313, 1028.3932479836933, 1032.6024480575034, 1050.1283934334483, 1058.1799105693149, 6222.377762223741, 6561.245456772437, 6597.274299827015, 6735.326467353226, 6789.864202716017, 460.88938654722625, 484.0923268870867, 489.2563303778237, 490.8920037591724, 495.54162399780057, 4361.80371883266, 4365.890852728644, 4484.641228701696, 4486.080353527519, 4505.414296141481, 11890.167688261912, 12192.39038048094, 12312.076594255466, 12335.0, 12342.444589993473, 11275.097992160607, 11727.120465965087, 11750.882491174978, 12208.0, 12256.325902075472, 8704.086070962585, 9116.498592577473, 9128.49793261377, 9143.817123657489, 9144.405613635092, 6461.224653041665, 6684.866302674017, 6847.828804279933, 6894.620795856276, 6990.0, 10907.83638245411, 11235.66293011211, 11277.379744114152, 11367.829482557934, 11496.0, 10086.798264034825, 10811.945940270249, 11500.597479088192, 11612.0, 11693.064554835762, 8731.781705457415, 9234.630614775333, 9234.907650923673, 9284.72145835626, 9354.111359420844, 9196.08039196075, 9335.299852510952, 9340.71977840666, 9345.0, 9403.576839042124, 8336.374771892253, 8375.659894417107, 8418.074011858569, 8521.53131577769, 8521.701740439055, 8133.959330203311, 8252.628631711708, 8305.335573154254, 8335.458195217292, 8356.582170891552, 7780.183080776437, 7804.297613214838, 7806.453548251568, 7869.3311068559815, 7910.129885712682, 5562.66624002545, 5616.803411880564, 5618.915716264341, 5648.519875810438, 5836.854078648068, 5723.370429252862, 5769.59612827098, 5780.30636323627, 5787.884242315215, 5872.471477567039], "value": 4944.376125463582, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.164013906416, 355.90924314299866, 356.0990693545331, 356.59170250063676, 360.70782666040515, 5432.266644003058, 5495.642783219088, 5498.615096943216, 5502.0, 5517.393086760453, 5141.845744627667, 5171.922421163686, 5293.602979776513, 5331.013762453939, 5450.345958484977, 4248.787560621972, 4255.36169574564, 4262.0, 4284.892877678052, 4286.399904013425, 4276.187524370375, 4290.0, 4290.828366865321, 4294.763787991659, 4322.335900678246, 4098.672106231509, 4207.200631879933, 4224.112936283384, 4244.596763307481, 4259.659227261827, 4232.386303985935, 4252.213340532012, 4255.1064276501975, 4260.659147268211, 4262.744235345889, 3804.2201348723447, 3813.923721525554, 3817.2556351511466, 3818.198178382543, 3822.7515211511163, 5015.523525265094, 5231.032259032091, 5231.163013917793, 5264.842054738326, 5280.630355875091, 4813.0, 4835.153848076608, 4838.733869637169, 4850.854474365739, 4861.805527778884, 5612.550995920316, 5614.466625670555, 5634.267545219136, 5650.74571644277, 5659.8585035374035, 4058.7767672777986, 4100.466939297902, 4125.484314460692, 4128.422020917074, 4152.065785198336, 5752.654840709571, 5786.132080187964, 5786.826395208151, 5796.0, 5798.391168927286, 4166.6250037496775, 4180.91638167241, 4186.5604111568455, 4192.979035104805, 4231.936520952188, 4110.753354798721, 4226.36604509326, 4275.593818587229, 4276.994906197041, 4283.87148385549, 4803.879903002383, 4806.7836947337455, 4807.4471435784635, 4813.855584332476, 4840.394950631171, 5656.971715141398, 5667.518260947783, 5706.600537962303, 5744.885102297931, 5746.655200687972, 1444.2995147353524, 1463.9268036598128, 1472.4625511688157, 1482.6664000599778, 1483.7922690823293, 930.8324501589643, 939.234523863063, 949.662869681267, 957.8802649668792, 967.5162418790604, 4878.829240976549, 4889.408613434885, 4901.754912254373, 4903.289023091632, 4923.0, 5121.258772017532, 5161.735374833115, 5180.326557547494, 5184.585233181337, 5188.299579556787, 4853.199222128309, 4864.902701945941, 4896.2410826321975, 4898.338724272177, 5106.795728170906, 3035.8330291833718, 3066.3254084101463, 3070.3398769264268, 3071.508558630653, 3071.769617278713, 5965.284165900033, 5977.462028417463, 5981.6710080945095, 5987.0, 5993.790217342459, 5618.747156377973, 5835.708214589254, 5849.88300233993, 5865.677387743715, 5892.705364731746, 5982.880342393128, 5992.790252341234, 5994.400559943971, 5995.940040599538, 6014.18808460861, 5640.887182256414, 5828.417158284307, 5830.737616807169, 5878.853623543368, 5892.0, 5740.51205647508, 5740.913886291625, 5779.335376431764, 5791.799116335766, 5815.45890339052, 5989.850253743692, 5991.400859913973, 5993.520518358608, 6069.0, 6081.027035674448, 4101.569335219879, 4185.204811085884, 4194.39181318701, 4234.407182994321, 4260.786960652017, 5598.748056337393, 5632.408597097246, 5783.19035335045, 5820.243368362255, 5859.121131830259, 5639.2668953035845, 5656.462636049569, 5660.801871934463, 5669.688167150846, 5690.2887139107615, 5492.890142197213, 5638.0, 5643.971780141073, 5685.0, 5688.829335119953, 5513.283273174622, 5599.160125981135, 5622.859428514156, 5637.661740295595, 5641.464060914206, 5653.745581448927, 5706.058500347396, 5708.686022268649, 5739.684317362585, 5756.58963553932, 5364.329458817648, 5555.50000499957, 5565.916511252415, 5577.776888924398, 5607.859803504945], "value": 4301.938233159217, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.4143533785001, 792.520525082325, 802.4703695560927, 825.5005721538469, 900.0594378874076, 15689.0, 15734.0, 15934.92032539837, 16700.83299167008, 16752.246148923314, 14766.409343626243, 14978.475753348635, 14989.025713328627, 15007.099574025538, 15014.324355404005, 714.885618301072, 827.3505298340804, 835.8411901738665, 846.8645016797313, 858.4548811504677, 843.0768308701993, 847.2501835875264, 852.6802449081595, 853.6670698427619, 853.8164294676643, 904.2539904578716, 905.7373361725092, 906.1572737354257, 906.650939388337, 906.8095699902996, 885.7209978856672, 891.8662200669886, 892.5894088719165, 893.1738142218487, 893.5889490834224, 664.8072059102855, 664.9767258145941, 666.2005593288026, 666.8466252761843, 666.9032990216391, 14246.501372451914, 14304.499342522962, 14395.424183032666, 14404.711905761826, 14430.567082987527, 15857.524274271675, 15879.126648034353, 15986.200689965455, 16072.517824465285, 16124.274407651685, 13076.476940922352, 13194.200373243275, 13265.535706250332, 13333.0, 13394.330283485786, 3999.8800035998966, 4102.364133559303, 4110.095778928631, 4238.554951730086, 4241.724287921275, 81923.18076819215, 82297.17702822955, 82329.0, 82681.58659206725, 83086.58456707677, 10687.732806679895, 10705.9464702676, 11138.94430527842, 11362.375069371099, 11366.772664546663, 24114.0, 24122.51754964926, 24148.63777043346, 24182.516349672907, 24190.758092419204, 8598.5700714964, 8656.350773691998, 8734.563271836383, 8955.194032536972, 9117.954410227907, 4695.624350051988, 4836.758162091881, 4884.731339776346, 5005.72468514228, 5106.336176297058, 3701.981490092532, 3856.0, 3857.3828187489867, 3859.7684138951754, 3868.4777555030273, 715.6135686729212, 746.3730466408243, 749.9775006749807, 752.834376437183, 757.7196437318228, 685.4927353758183, 721.859237448703, 770.2374649097466, 778.9707662690732, 785.2696991797683, 10068.597256109673, 10310.0, 10437.530311136017, 10614.203934704929, 10961.945190274157, 719.7624783821326, 732.3226015935238, 743.6096049573908, 760.4182800157884, 770.4991755359061, 5007.173816320339, 5053.498518233425, 5142.97428512855, 5149.871253218625, 5161.4322424533275, 67087.66456167688, 68974.0, 71727.28272717206, 122297.38851305864, 124619.0, 108089.45955270331, 110252.4487377574, 111844.0, 112847.43576282066, 113100.0, 62967.99999999908, 94521.52739236398, 99400.0, 99927.50036249918, 100715.49642251742, 9518.286128540249, 9536.713898583053, 9581.568829402833, 9740.707778766648, 9836.114749672564, 56019.0, 57142.0, 57670.42329576818, 59467.40532594792, 59795.0, 73572.0, 79030.0, 82529.58735206285, 85312.0, 86219.5689021576, 11912.940435297767, 11963.0, 12043.156979011736, 12097.395130243276, 12170.513179472722, 13566.93216533911, 13851.099678520864, 13964.092333998258, 14150.929245353707, 14172.149671019772, 12540.686482838002, 13111.737765244832, 13132.474701011852, 13248.403821827847, 13388.397522111723, 12567.4128942937, 12797.29614871191, 12920.676983075498, 12960.944775794129, 13605.115667481581, 12549.498020079094, 13047.478100876238, 13055.412506437044, 13349.866501334862, 13667.521636742666, 10599.364038157735, 10649.733756656145, 10713.625023124154, 10782.67651970442, 10898.18263630215, 10843.349399036084, 11070.889291107307, 11145.83281250798, 11381.203315767816, 11436.0], "value": 7821.458150883043, "unit": "score"}], "name": "dromaeo_css", "value": 7636.256252344884, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 120.0, 109.0, 109.0, 110.0, 109.0, 110.0, 110.0, 109.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 121.0, 144.0, 180.0, 116.0, 144.0, 147.0, 119.0, 184.0, 115.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 161.0, 161.0, 163.0, 159.0, 161.0, 158.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 177.0, 74.0, 75.0, 75.0, 74.0, 159.0, 75.0], "value": 93.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 154.0, 84.0, 84.0, 84.0, 140.0, 84.0, 83.0, 140.0], "value": 102.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 147.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 51.0, 52.0, 52.0, 51.0, 52.0, 52.0, 50.0, 53.0, 52.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 60.0, 59.0, 60.0, 60.0, 60.0, 60.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 72.0, 81.0, 77.0, 72.0, 73.0, 72.0, 81.0, 72.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 118.0, 119.0, 131.0, 116.0, 124.0, 116.0, 123.0, 184.0, 127.0], "value": 128.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 151.0, 155.0, 150.0, 153.0, 150.0, 149.0, 152.0, 150.0, 159.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 60.0, 61.0, 60.0, 64.0, 60.0, 61.0, 60.0, 60.0], "value": 60.8, "unit": "score"}], "name": "kraken", "value": 1492.6, "alertThreshold": 2.0}]} 12:44:24 INFO - Return code: 0 12:44:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:44:24 INFO - # TBPL SUCCESS # 12:44:24 INFO - Running post-action listener: _resource_record_post_action 12:44:24 INFO - [mozharness: 2016-09-20 19:44:24.290264Z] Finished run-tests step (success) 12:44:24 INFO - Running post-run listener: _resource_record_post_run 12:44:24 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 288804864; Read time: 0; Write time: 220984 12:44:24 INFO - TinderboxPrint: CPU usage
12.9% 12:44:24 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 12:44:24 INFO - TinderboxPrint: I/O write bytes / time
288,804,864 / 220,984 12:44:24 INFO - TinderboxPrint: CPU idle
8,259.6 (87.1%) 12:44:24 INFO - TinderboxPrint: CPU user
1,179.8 (12.4%) 12:44:24 INFO - TinderboxPrint: Swap in / out
0 / 0 12:44:24 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 71565312; Read time: 0; Write time: 43668 12:44:24 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 217239552; Read time: 0; Write time: 177316 12:44:24 INFO - Running post-run listener: _upload_blobber_files 12:44:24 INFO - Blob upload gear active. 12:44:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:44:24 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 12:44:24 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:44:24 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:44:25 INFO - (blobuploader) - INFO - Open directory for files ... 12:44:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:44:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:44:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:44:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:44:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:44:26 INFO - (blobuploader) - INFO - Done attempting. 12:44:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:44:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:44:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:44:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:44:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:44:31 INFO - (blobuploader) - INFO - Done attempting. 12:44:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:44:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:44:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:44:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:44:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:44:31 INFO - (blobuploader) - INFO - Done attempting. 12:44:31 INFO - (blobuploader) - INFO - Iteration through files over. 12:44:31 INFO - Return code: 0 12:44:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:44:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:44:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cabe88137eed2b9a8a9cc800d041f9de22a468b9df8018216fa099d37c5a0ac76381c1bfbbe833b6ceab389fc0dad2b797df2e92c424dd2a6e81ab191b0befb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe4d1fab53c6558b604479aba9c4672f06952155e0aac76482cb7344bbec4dec34ca47acea7d0074d1ca3699db629c8514b657e636740d10948e5224e287443a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed53e7b81c442abf95cdcd9ddce30d05b6845ff0489521d8086d284d24adf31f079e1700f392702b535512cbb270c5caf38fda9a9743b82b18ca00a13eae4df0"} 12:44:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:44:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:44:31 INFO - Contents: 12:44:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cabe88137eed2b9a8a9cc800d041f9de22a468b9df8018216fa099d37c5a0ac76381c1bfbbe833b6ceab389fc0dad2b797df2e92c424dd2a6e81ab191b0befb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe4d1fab53c6558b604479aba9c4672f06952155e0aac76482cb7344bbec4dec34ca47acea7d0074d1ca3699db629c8514b657e636740d10948e5224e287443a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed53e7b81c442abf95cdcd9ddce30d05b6845ff0489521d8086d284d24adf31f079e1700f392702b535512cbb270c5caf38fda9a9743b82b18ca00a13eae4df0"} 12:44:31 INFO - Running post-run listener: copy_logs_to_upload_dir 12:44:31 INFO - Copying logs to upload dir... 12:44:31 INFO - mkdir: /builds/slave/test/build/upload/logs 12:44:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.121134 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-20 12:44:31.725932) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 12:44:31.729436) ========= 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=eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901 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-Beta/sha512/cabe88137eed2b9a8a9cc800d041f9de22a468b9df8018216fa099d37c5a0ac76381c1bfbbe833b6ceab389fc0dad2b797df2e92c424dd2a6e81ab191b0befb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe4d1fab53c6558b604479aba9c4672f06952155e0aac76482cb7344bbec4dec34ca47acea7d0074d1ca3699db629c8514b657e636740d10948e5224e287443a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed53e7b81c442abf95cdcd9ddce30d05b6845ff0489521d8086d284d24adf31f079e1700f392702b535512cbb270c5caf38fda9a9743b82b18ca00a13eae4df0"} build_url:https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011215 build_url: 'https://queue.taskcluster.net/v1/task/GD5O3cGPRb2b_emn2TkDOw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cabe88137eed2b9a8a9cc800d041f9de22a468b9df8018216fa099d37c5a0ac76381c1bfbbe833b6ceab389fc0dad2b797df2e92c424dd2a6e81ab191b0befb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe4d1fab53c6558b604479aba9c4672f06952155e0aac76482cb7344bbec4dec34ca47acea7d0074d1ca3699db629c8514b657e636740d10948e5224e287443a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed53e7b81c442abf95cdcd9ddce30d05b6845ff0489521d8086d284d24adf31f079e1700f392702b535512cbb270c5caf38fda9a9743b82b18ca00a13eae4df0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 12:44:31.759232) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 12:44:31.759911) ========= 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=eca2ab57546de7b41f256b19000001ef-1474398993.511420-1346357901 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004491 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 12:44:31.840757) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 12:44:31.841161) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 12:44:31.841488) ========= ========= Total master_lag: 0.18 =========