builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-024 starttime: 1475539277.9 results: success (0) buildid: 20161003141658 builduid: 8d450f5662b5407ea10e6e4670eaef70 revision: f3362d645d5e0c383f872775da6ad6adfaf339bb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 17:01:17.900455) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 17:01:17.901075) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 17:01:17.901402) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 17:01:17.916797) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 17:01:17.917189) ========= 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=41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-03 17:01:18-- 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% 215M=0s 2016-10-03 17:01:18 (215 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.147299 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 17:01:18.083325) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 17:01:18.083690) ========= 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=41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022419 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 17:01:18.138114) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 17:01:18.138503) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f3362d645d5e0c383f872775da6ad6adfaf339bb --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 f3362d645d5e0c383f872775da6ad6adfaf339bb --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=41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-03 17:01:18,846 truncating revision to first 12 chars 2016-10-03 17:01:18,847 Setting DEBUG logging. 2016-10-03 17:01:18,847 attempt 1/10 2016-10-03 17:01:18,847 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f3362d645d5e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-03 17:01:19,169 unpacking tar archive at: mozilla-beta-f3362d645d5e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.666196 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 17:01:18.818875) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 17:01:18.819222) ========= script_repo_revision: f3362d645d5e0c383f872775da6ad6adfaf339bb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 17:01:18.819680) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 17:01:18.819986) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 17:01:18.830848) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-03 17:01:18.831217) ========= /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=41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:01:19 INFO - MultiFileLogger online at 20161003 17:01:19 in /builds/slave/test 17:01:19 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 17:01:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:01:19 INFO - {'append_to_log': False, 17:01:19 INFO - 'base_work_dir': '/builds/slave/test', 17:01:19 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:01:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:01:19 INFO - 'branch': 'Mozilla-Beta', 17:01:19 INFO - 'buildbot_json_path': 'buildprops.json', 17:01:19 INFO - 'config_files': ('talos/linux_config.py',), 17:01:19 INFO - 'default_actions': ('clobber', 17:01:19 INFO - 'read-buildbot-config', 17:01:19 INFO - 'download-and-extract', 17:01:19 INFO - 'populate-webroot', 17:01:19 INFO - 'create-virtualenv', 17:01:19 INFO - 'install', 17:01:19 INFO - 'run-tests'), 17:01:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:01:19 INFO - 'download_minidump_stackwalk': True, 17:01:19 INFO - 'download_symbols': 'ondemand', 17:01:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:01:19 INFO - 'tooltool.py': '/tools/tooltool.py', 17:01:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:01:19 INFO - '/tools/misc-python/virtualenv.py')}, 17:01:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:01:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:01:19 INFO - 'installer_path': 'installer.exe', 17:01:19 INFO - 'log_level': 'info', 17:01:19 INFO - 'log_name': 'talos', 17:01:19 INFO - 'log_to_console': True, 17:01:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:01:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:01:19 INFO - 'opt_config_files': (), 17:01:19 INFO - 'pip_index': False, 17:01:19 INFO - 'sps_profile': False, 17:01:19 INFO - 'sps_profile_interval': 0, 17:01:19 INFO - 'suite': 'dromaeojs', 17:01:19 INFO - 'system_bits': '32', 17:01:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:01:19 INFO - 'title': 'talos-linux64-ix-024', 17:01:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:01:19 INFO - 'use_talos_json': True, 17:01:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:01:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:01:19 INFO - 'work_dir': 'build'} 17:01:19 INFO - [mozharness: 2016-10-04 00:01:19.622442Z] Running clobber step. 17:01:19 INFO - Running pre-action listener: _resource_record_pre_action 17:01:19 INFO - Running main action method: clobber 17:01:19 INFO - rmtree: /builds/slave/test/build 17:01:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:01:20 INFO - Running post-action listener: _resource_record_post_action 17:01:20 INFO - [mozharness: 2016-10-04 00:01:20.612650Z] Finished clobber step (success) 17:01:20 INFO - [mozharness: 2016-10-04 00:01:20.612753Z] Running read-buildbot-config step. 17:01:20 INFO - Running pre-action listener: _resource_record_pre_action 17:01:20 INFO - Running main action method: read_buildbot_config 17:01:20 INFO - Using buildbot properties: 17:01:20 INFO - { 17:01:20 INFO - "project": "", 17:01:20 INFO - "product": "firefox", 17:01:20 INFO - "script_repo_revision": "production", 17:01:20 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 17:01:20 INFO - "repository": "", 17:01:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 17:01:20 INFO - "buildid": "20161003141658", 17:01:20 INFO - "pgo_build": "True", 17:01:20 INFO - "basedir": "/builds/slave/test", 17:01:20 INFO - "buildnumber": 407, 17:01:20 INFO - "slavename": "talos-linux64-ix-024", 17:01:20 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:01:20 INFO - "platform": "ubuntu64_hw", 17:01:20 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 17:01:20 INFO - "branch": "mozilla-beta", 17:01:20 INFO - "repo_path": "releases/mozilla-beta", 17:01:20 INFO - "revision": "f3362d645d5e0c383f872775da6ad6adfaf339bb", 17:01:20 INFO - "stage_platform": "linux64-pgo", 17:01:20 INFO - "builduid": "8d450f5662b5407ea10e6e4670eaef70", 17:01:20 INFO - "slavebuilddir": "test" 17:01:20 INFO - } 17:01:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 17:01:20 INFO - Running post-action listener: _resource_record_post_action 17:01:20 INFO - [mozharness: 2016-10-04 00:01:20.615227Z] Finished read-buildbot-config step (success) 17:01:20 INFO - [mozharness: 2016-10-04 00:01:20.615317Z] Running download-and-extract step. 17:01:20 INFO - Running pre-action listener: _resource_record_pre_action 17:01:20 INFO - Running main action method: download_and_extract 17:01:20 INFO - mkdir: /builds/slave/test/build/tests 17:01: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')]} 17:01:20 INFO - https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:01:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:01:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:01:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:01:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:01:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:01:20 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:01:20 INFO - retry: Failed, sleeping 30 seconds before retrying 17:01:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:01:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:01:50 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:01:50 INFO - retry: Failed, sleeping 60 seconds before retrying 17:02:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:02:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:02:50 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:02:50 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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! 17:02:50 INFO - Caught exception: HTTP Error 404: Not Found 17:02:50 INFO - Caught exception: HTTP Error 404: Not Found 17:02:50 INFO - Caught exception: HTTP Error 404: Not Found 17:02:50 INFO - trying https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:02:50 INFO - Downloading https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:02:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:02:51 INFO - Downloaded 1327 bytes. 17:02:51 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:02:51 INFO - Using the following test package requirements: 17:02:51 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 17:02:51 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:02:51 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 17:02:51 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:02:51 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 17:02:51 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:02:51 INFO - u'jsshell-linux-x86_64.zip'], 17:02:51 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:02:51 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 17:02:51 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 17:02:51 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:02:51 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 17:02:51 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:02:51 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 17:02:51 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:02:51 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 17:02:51 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:02:51 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 17:02:51 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:02:51 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')]} 17:02:51 INFO - https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:02:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:02:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:02:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:02:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:02:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:02:51 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:02:51 INFO - retry: Failed, sleeping 30 seconds before retrying 17:03:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:03:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:03:21 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:03:21 INFO - retry: Failed, sleeping 60 seconds before retrying 17:04:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:04:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:04:21 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:04:21 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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! 17:04:21 INFO - Caught exception: HTTP Error 404: Not Found 17:04:21 INFO - Caught exception: HTTP Error 404: Not Found 17:04:21 INFO - Caught exception: HTTP Error 404: Not Found 17:04:21 INFO - trying https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:04:21 INFO - Downloading https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:04:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:04:22 INFO - Downloaded 21841958 bytes. 17:04:22 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'] 17:04:22 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 17:04:23 INFO - Return code: 0 17:04:23 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 17:04:23 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')]} 17:04:23 INFO - https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:04:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:04:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:04:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:04:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:04:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:04:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:04:23 INFO - retry: Failed, sleeping 30 seconds before retrying 17:04:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:04:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:04:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:04:53 INFO - retry: Failed, sleeping 60 seconds before retrying 17:05:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:05:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:05:53 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:05:53 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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! 17:05:53 INFO - Caught exception: HTTP Error 404: Not Found 17:05:53 INFO - Caught exception: HTTP Error 404: Not Found 17:05:53 INFO - Caught exception: HTTP Error 404: Not Found 17:05:53 INFO - trying https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:05:53 INFO - Downloading https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:05:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:05:54 INFO - Downloaded 21841958 bytes. 17:05:54 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'] 17:05:54 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 17:05:55 INFO - Return code: 0 17:05:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:05:55 INFO - https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:05:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:05:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:05:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:05:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:05:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:05:55 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:05:55 INFO - retry: Failed, sleeping 30 seconds before retrying 17:06:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:06:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:06:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:06:25 INFO - retry: Failed, sleeping 60 seconds before retrying 17:07:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:07:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:07:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:07:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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! 17:07:26 INFO - Caught exception: HTTP Error 404: Not Found 17:07:26 INFO - Caught exception: HTTP Error 404: Not Found 17:07:26 INFO - Caught exception: HTTP Error 404: Not Found 17:07:26 INFO - trying https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:07:26 INFO - Downloading https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:07:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:07:26 INFO - Downloaded 14413740 bytes. 17:07:26 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'] 17:07:26 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 17:07:27 INFO - Return code: 0 17:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:07:27 INFO - https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:07:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:07:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:07:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:07:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:07:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:07:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:07:27 INFO - retry: Failed, sleeping 30 seconds before retrying 17:07:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:07:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:07:57 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:07:57 INFO - retry: Failed, sleeping 60 seconds before retrying 17:08:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:08:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:08:57 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:08:57 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MakJ_basQu2JjG-Tjg84HQ/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! 17:08:57 INFO - Caught exception: HTTP Error 404: Not Found 17:08:57 INFO - Caught exception: HTTP Error 404: Not Found 17:08:57 INFO - Caught exception: HTTP Error 404: Not Found 17:08:57 INFO - trying https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:08:57 INFO - Downloading https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:08:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/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 17:08:58 INFO - Downloaded 56782472 bytes. 17:08:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:08:58 INFO - mkdir: /builds/slave/test/properties 17:08:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:08:58 INFO - Writing to file /builds/slave/test/properties/build_url 17:08:58 INFO - Contents: 17:08:58 INFO - build_url:https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:08:58 INFO - Running post-action listener: _resource_record_post_action 17:08:58 INFO - Running post-action listener: set_extra_try_arguments 17:08:58 INFO - [mozharness: 2016-10-04 00:08:58.875032Z] Finished download-and-extract step (success) 17:08:58 INFO - [mozharness: 2016-10-04 00:08:58.875267Z] Running populate-webroot step. 17:08:58 INFO - Running pre-action listener: _resource_record_pre_action 17:08:58 INFO - Running main action method: populate_webroot 17:08:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:08:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:08:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:08:58 INFO - '--tppagecycles', 17:08:58 INFO - '7'], 17:08:58 INFO - 'tests': ['tsvgm']}}, 17:08:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:08:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:08:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:08:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:08:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:08:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:08:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:58 INFO - 'talos_options': ['--disable-e10s'], 17:08:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:08:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:08:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:58 INFO - 'talos_options': ['--disable-e10s'], 17:08:58 INFO - 'tests': ['damp', 'tps']}, 17:08:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:58 INFO - 'tests': ['damp', 'tps']}, 17:08:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:08:58 INFO - 'tests': ['dromaeo_dom']}, 17:08:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:08:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:08:58 INFO - 'tests': ['basic_compositor_video']}, 17:08:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:08:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:08:58 INFO - 'tests': ['a11yr', 17:08:58 INFO - 'ts_paint', 17:08:58 INFO - 'tpaint', 17:08:58 INFO - 'sessionrestore', 17:08:58 INFO - 'sessionrestore_no_auto_restore', 17:08:58 INFO - 'tabpaint']}, 17:08:58 INFO - 'other-e10s': {'tests': ['a11yr', 17:08:58 INFO - 'ts_paint', 17:08:58 INFO - 'tpaint', 17:08:58 INFO - 'sessionrestore', 17:08:58 INFO - 'sessionrestore_no_auto_restore', 17:08:58 INFO - 'tabpaint']}, 17:08:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:08:58 INFO - 'ts_paint', 17:08:58 INFO - 'tpaint', 17:08:58 INFO - 'sessionrestore', 17:08:58 INFO - 'sessionrestore_no_auto_restore', 17:08:58 INFO - 'tabpaint']}, 17:08:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:08:58 INFO - 'ts_paint', 17:08:58 INFO - 'tpaint', 17:08:58 INFO - 'sessionrestore', 17:08:58 INFO - 'sessionrestore_no_auto_restore', 17:08:58 INFO - 'tabpaint']}, 17:08:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:08:58 INFO - 'tests': ['a11yr', 17:08:58 INFO - 'ts_paint', 17:08:58 INFO - 'tpaint', 17:08:58 INFO - 'sessionrestore', 17:08:58 INFO - 'sessionrestore_no_auto_restore', 17:08:58 INFO - 'tabpaint']}, 17:08:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:08:58 INFO - 'tests': ['a11yr', 17:08:58 INFO - 'ts_paint', 17:08:58 INFO - 'tpaint', 17:08:58 INFO - 'sessionrestore', 17:08:58 INFO - 'sessionrestore_no_auto_restore', 17:08:58 INFO - 'tabpaint']}, 17:08:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:08:58 INFO - 'tests': ['tsvgx', 17:08:58 INFO - 'tsvgr_opacity', 17:08:58 INFO - 'tart', 17:08:58 INFO - 'tscrollx', 17:08:58 INFO - 'cart']}, 17:08:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:08:58 INFO - 'tsvgr_opacity', 17:08:58 INFO - 'tart', 17:08:58 INFO - 'tscrollx', 17:08:58 INFO - 'cart']}, 17:08:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:58 INFO - 'talos_options': ['--disable-e10s'], 17:08:58 INFO - 'tests': ['tp5o']}, 17:08:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:58 INFO - 'tests': ['tp5o']}, 17:08:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:58 INFO - 'talos_options': ['--disable-e10s', 17:08:58 INFO - '--xperf_path', 17:08:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:08:58 INFO - 'tests': ['tp5n']}, 17:08:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:58 INFO - 'talos_options': ['--xperf_path', 17:08:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:08:58 INFO - 'tests': ['tp5n']}}, 17:08:58 INFO - 'talos.zip': {'path': '', 17:08:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:08:58 INFO - Running post-action listener: _resource_record_post_action 17:08:58 INFO - [mozharness: 2016-10-04 00:08:58.894989Z] Finished populate-webroot step (success) 17:08:58 INFO - [mozharness: 2016-10-04 00:08:58.895079Z] Running create-virtualenv step. 17:08:58 INFO - Running pre-action listener: _resource_record_pre_action 17:08:58 INFO - Running main action method: create_virtualenv 17:08:58 INFO - Creating virtualenv /builds/slave/test/build/venv 17:08:58 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 17:08:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:08:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:08:58 INFO - Using real prefix '/usr' 17:08:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:08:59 INFO - Installing distribute.............................................................................................................................................................................................done. 17:09:01 INFO - Installing pip.................done. 17:09:01 INFO - Return code: 0 17:09:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:09:01 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')]} 17:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:01 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')]} 17:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:01 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 0xc92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf513b0>, '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 0xf522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52740>, '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': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', '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 17:09:01 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 17:09:01 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 17:09:01 INFO - Using env: {'DISPLAY': ':0', 17:09:01 INFO - 'HOME': '/home/cltbld', 17:09:01 INFO - 'LANG': 'en_US.UTF-8', 17:09:01 INFO - 'LANGUAGE': 'en_US:en', 17:09:01 INFO - 'LOGNAME': 'cltbld', 17:09:01 INFO - 'MAIL': '/var/mail/cltbld', 17:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:01 INFO - 'MOZ_NO_REMOTE': '1', 17:09:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:01 INFO - 'NO_EM_RESTART': '1', 17:09:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:01 INFO - 'PWD': '/builds/slave/test', 17:09:01 INFO - 'SHELL': '/bin/bash', 17:09:01 INFO - 'SHLVL': '1', 17:09:01 INFO - 'TERM': 'linux', 17:09:01 INFO - 'TMOUT': '86400', 17:09:01 INFO - 'USER': 'cltbld', 17:09:01 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', 17:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:01 INFO - '_': '/tools/buildbot/bin/python'} 17:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:01 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 17:09:01 INFO - Cleaning up... 17:09:01 INFO - Return code: 0 17:09:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:09:01 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')]} 17:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:01 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')]} 17:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:01 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 0xc92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf513b0>, '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 0xf522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52740>, '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': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', '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 17:09:01 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 17:09:01 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 17:09:01 INFO - Using env: {'DISPLAY': ':0', 17:09:01 INFO - 'HOME': '/home/cltbld', 17:09:01 INFO - 'LANG': 'en_US.UTF-8', 17:09:01 INFO - 'LANGUAGE': 'en_US:en', 17:09:01 INFO - 'LOGNAME': 'cltbld', 17:09:01 INFO - 'MAIL': '/var/mail/cltbld', 17:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:01 INFO - 'MOZ_NO_REMOTE': '1', 17:09:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:01 INFO - 'NO_EM_RESTART': '1', 17:09:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:01 INFO - 'PWD': '/builds/slave/test', 17:09:01 INFO - 'SHELL': '/bin/bash', 17:09:01 INFO - 'SHLVL': '1', 17:09:01 INFO - 'TERM': 'linux', 17:09:01 INFO - 'TMOUT': '86400', 17:09:01 INFO - 'USER': 'cltbld', 17:09:01 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', 17:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:01 INFO - '_': '/tools/buildbot/bin/python'} 17:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:01 INFO - Downloading/unpacking psutil>=3.1.1 17:09:01 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 17:09:01 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 17:09:01 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 17:09:01 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 17:09:01 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 17:09:01 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 17:09:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:09:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:09:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:09:03 INFO - Installing collected packages: psutil 17:09:03 INFO - Running setup.py install for psutil 17:09:03 INFO - building 'psutil._psutil_linux' extension 17:09:03 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 17:09:04 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 17:09:04 INFO - building 'psutil._psutil_posix' extension 17:09:04 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 17:09:04 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 17:09:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:09:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:09:04 INFO - Successfully installed psutil 17:09:04 INFO - Cleaning up... 17:09:04 INFO - Return code: 0 17:09:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:09:04 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')]} 17:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:04 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')]} 17:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:04 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 0xc92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf513b0>, '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 0xf522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52740>, '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': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', '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 17:09:04 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 17:09:04 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 17:09:04 INFO - Using env: {'DISPLAY': ':0', 17:09:04 INFO - 'HOME': '/home/cltbld', 17:09:04 INFO - 'LANG': 'en_US.UTF-8', 17:09:04 INFO - 'LANGUAGE': 'en_US:en', 17:09:04 INFO - 'LOGNAME': 'cltbld', 17:09:04 INFO - 'MAIL': '/var/mail/cltbld', 17:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:04 INFO - 'MOZ_NO_REMOTE': '1', 17:09:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:04 INFO - 'NO_EM_RESTART': '1', 17:09:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:04 INFO - 'PWD': '/builds/slave/test', 17:09:04 INFO - 'SHELL': '/bin/bash', 17:09:04 INFO - 'SHLVL': '1', 17:09:04 INFO - 'TERM': 'linux', 17:09:04 INFO - 'TMOUT': '86400', 17:09:04 INFO - 'USER': 'cltbld', 17:09:04 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', 17:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:04 INFO - '_': '/tools/buildbot/bin/python'} 17:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:09:04 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 17:09:04 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 17:09:04 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 17:09:04 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 17:09:04 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 17:09:04 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 17:09:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:09:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:09:06 INFO - Installing collected packages: mozsystemmonitor 17:09:06 INFO - Running setup.py install for mozsystemmonitor 17:09:06 INFO - Successfully installed mozsystemmonitor 17:09:06 INFO - Cleaning up... 17:09:06 INFO - Return code: 0 17:09:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:09: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')]} 17:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09: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')]} 17:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:06 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 0xc92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf513b0>, '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 0xf522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52740>, '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': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', '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 17:09:06 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 17:09:06 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 17:09:06 INFO - Using env: {'DISPLAY': ':0', 17:09:06 INFO - 'HOME': '/home/cltbld', 17:09:06 INFO - 'LANG': 'en_US.UTF-8', 17:09:06 INFO - 'LANGUAGE': 'en_US:en', 17:09:06 INFO - 'LOGNAME': 'cltbld', 17:09:06 INFO - 'MAIL': '/var/mail/cltbld', 17:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:06 INFO - 'MOZ_NO_REMOTE': '1', 17:09:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:06 INFO - 'NO_EM_RESTART': '1', 17:09:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:06 INFO - 'PWD': '/builds/slave/test', 17:09:06 INFO - 'SHELL': '/bin/bash', 17:09:06 INFO - 'SHLVL': '1', 17:09:06 INFO - 'TERM': 'linux', 17:09:06 INFO - 'TMOUT': '86400', 17:09:06 INFO - 'USER': 'cltbld', 17:09:06 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', 17:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:06 INFO - '_': '/tools/buildbot/bin/python'} 17:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:06 INFO - Downloading/unpacking jsonschema==2.5.1 17:09:06 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 17:09:06 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 17:09:06 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 17:09:06 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 17:09:06 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 17:09:06 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 17:09:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:09:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:09 INFO - Downloading functools32-3.2.3-2.tar.gz 17:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:09:09 INFO - warning: no files found matching '*.txt' 17:09:09 INFO - no previously-included directories found matching 'build' 17:09:09 INFO - no previously-included directories found matching 'dist' 17:09:09 INFO - no previously-included directories found matching '.git*' 17:09:09 INFO - Installing collected packages: jsonschema, functools32 17:09:09 INFO - Running setup.py install for functools32 17:09:09 INFO - warning: no files found matching '*.txt' 17:09:09 INFO - no previously-included directories found matching 'build' 17:09:09 INFO - no previously-included directories found matching 'dist' 17:09:09 INFO - no previously-included directories found matching '.git*' 17:09:09 INFO - Successfully installed jsonschema functools32 17:09:09 INFO - Cleaning up... 17:09:09 INFO - Return code: 0 17:09:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:09:09 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')]} 17:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:09 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')]} 17:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:09 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 0xc92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf513b0>, '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 0xf522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52740>, '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': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', '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 17:09:09 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 17:09:09 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 17:09:09 INFO - Using env: {'DISPLAY': ':0', 17:09:09 INFO - 'HOME': '/home/cltbld', 17:09:09 INFO - 'LANG': 'en_US.UTF-8', 17:09:09 INFO - 'LANGUAGE': 'en_US:en', 17:09:09 INFO - 'LOGNAME': 'cltbld', 17:09:09 INFO - 'MAIL': '/var/mail/cltbld', 17:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:09 INFO - 'MOZ_NO_REMOTE': '1', 17:09:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:09 INFO - 'NO_EM_RESTART': '1', 17:09:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:09 INFO - 'PWD': '/builds/slave/test', 17:09:09 INFO - 'SHELL': '/bin/bash', 17:09:09 INFO - 'SHLVL': '1', 17:09:09 INFO - 'TERM': 'linux', 17:09:09 INFO - 'TMOUT': '86400', 17:09:09 INFO - 'USER': 'cltbld', 17:09:09 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', 17:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:09 INFO - '_': '/tools/buildbot/bin/python'} 17:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:09:09 INFO - Cleaning up... 17:09:09 INFO - Return code: 0 17:09:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:09:09 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')]} 17:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:09 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')]} 17:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:09 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 0xc92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf513b0>, '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 0xf522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52740>, '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': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', '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 17:09:09 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 17:09:09 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 17:09:09 INFO - Using env: {'DISPLAY': ':0', 17:09:09 INFO - 'HOME': '/home/cltbld', 17:09:09 INFO - 'LANG': 'en_US.UTF-8', 17:09:09 INFO - 'LANGUAGE': 'en_US:en', 17:09:09 INFO - 'LOGNAME': 'cltbld', 17:09:09 INFO - 'MAIL': '/var/mail/cltbld', 17:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:09 INFO - 'MOZ_NO_REMOTE': '1', 17:09:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:09 INFO - 'NO_EM_RESTART': '1', 17:09:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:09 INFO - 'PWD': '/builds/slave/test', 17:09:09 INFO - 'SHELL': '/bin/bash', 17:09:09 INFO - 'SHLVL': '1', 17:09:09 INFO - 'TERM': 'linux', 17:09:09 INFO - 'TMOUT': '86400', 17:09:09 INFO - 'USER': 'cltbld', 17:09:09 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', 17:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:09 INFO - '_': '/tools/buildbot/bin/python'} 17:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:09 INFO - Downloading/unpacking blobuploader==1.2.4 17:09:09 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 17:09:09 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 17:09:09 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 17:09:09 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 17:09:09 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 17:09:09 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 17:09:11 INFO - Downloading blobuploader-1.2.4.tar.gz 17:09:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:09:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:09:11 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 17:09:11 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 17:09:11 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 17:09:11 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 17:09:11 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 17:09:11 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 17:09:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:09:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:12 INFO - Downloading docopt-0.6.1.tar.gz 17:09:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:09:12 INFO - Installing collected packages: blobuploader, requests, docopt 17:09:12 INFO - Running setup.py install for blobuploader 17:09:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:09:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:09:12 INFO - Running setup.py install for requests 17:09:12 INFO - Running setup.py install for docopt 17:09:12 INFO - Successfully installed blobuploader requests docopt 17:09:12 INFO - Cleaning up... 17:09:13 INFO - Return code: 0 17:09:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:13 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 0xc92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf513b0>, '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 0xf522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52740>, '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': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', '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 17:09:13 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 17:09:13 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 17:09:13 INFO - Using env: {'DISPLAY': ':0', 17:09:13 INFO - 'HOME': '/home/cltbld', 17:09:13 INFO - 'LANG': 'en_US.UTF-8', 17:09:13 INFO - 'LANGUAGE': 'en_US:en', 17:09:13 INFO - 'LOGNAME': 'cltbld', 17:09:13 INFO - 'MAIL': '/var/mail/cltbld', 17:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:13 INFO - 'MOZ_NO_REMOTE': '1', 17:09:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:13 INFO - 'NO_EM_RESTART': '1', 17:09:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:13 INFO - 'PWD': '/builds/slave/test', 17:09:13 INFO - 'SHELL': '/bin/bash', 17:09:13 INFO - 'SHLVL': '1', 17:09:13 INFO - 'TERM': 'linux', 17:09:13 INFO - 'TMOUT': '86400', 17:09:13 INFO - 'USER': 'cltbld', 17:09:13 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', 17:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:13 INFO - '_': '/tools/buildbot/bin/python'} 17:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:09:13 INFO - Running setup.py (path:/tmp/pip-Vjv3Fq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:09:13 INFO - Running setup.py (path:/tmp/pip-ULswrM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:09:13 INFO - Running setup.py (path:/tmp/pip-ewMsRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:09:13 INFO - Running setup.py (path:/tmp/pip-jyoVpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:09:13 INFO - Running setup.py (path:/tmp/pip-H7JoJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:09:13 INFO - Running setup.py (path:/tmp/pip-CrsIpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:09:13 INFO - Running setup.py (path:/tmp/pip-s_TJqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:09:13 INFO - Running setup.py (path:/tmp/pip-KXWGvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:09:13 INFO - Running setup.py (path:/tmp/pip-RhyHgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:09:13 INFO - Running setup.py (path:/tmp/pip-33YNVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:09:13 INFO - Running setup.py (path:/tmp/pip-vW3zwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:09:14 INFO - Running setup.py (path:/tmp/pip-HeeUJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:09:14 INFO - Running setup.py (path:/tmp/pip-9yzvBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:09:14 INFO - Running setup.py (path:/tmp/pip-EX2wGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:14 INFO - Running setup.py (path:/tmp/pip-T90WDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:09:14 INFO - Running setup.py (path:/tmp/pip-ZpkEc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:09:14 INFO - Running setup.py (path:/tmp/pip-p6XSZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:09:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:09:14 INFO - Running setup.py install for manifestparser 17:09:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:09:14 INFO - Running setup.py install for mozcrash 17:09:14 INFO - Running setup.py install for mozdebug 17:09:14 INFO - Running setup.py install for mozdevice 17:09:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:09:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:09:14 INFO - Running setup.py install for mozfile 17:09:15 INFO - Running setup.py install for mozhttpd 17:09:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:09:15 INFO - Running setup.py install for mozinfo 17:09:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:09:15 INFO - Running setup.py install for mozInstall 17:09:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:09:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:09:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:09:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:09:15 INFO - Running setup.py install for mozleak 17:09:15 INFO - Running setup.py install for mozlog 17:09:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:09:15 INFO - Running setup.py install for moznetwork 17:09:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:09:15 INFO - Running setup.py install for mozprocess 17:09:15 INFO - Running setup.py install for mozprofile 17:09:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:09:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:09:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:09:15 INFO - Running setup.py install for mozrunner 17:09:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:09:15 INFO - Running setup.py install for mozscreenshot 17:09:16 INFO - Running setup.py install for moztest 17:09:16 INFO - Running setup.py install for mozversion 17:09:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:09:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:09:16 INFO - Cleaning up... 17:09:16 INFO - Return code: 0 17:09:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:16 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 0xc92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf513b0>, '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 0xf522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52740>, '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': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', '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 17:09:16 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 17:09:16 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 17:09:16 INFO - Using env: {'DISPLAY': ':0', 17:09:16 INFO - 'HOME': '/home/cltbld', 17:09:16 INFO - 'LANG': 'en_US.UTF-8', 17:09:16 INFO - 'LANGUAGE': 'en_US:en', 17:09:16 INFO - 'LOGNAME': 'cltbld', 17:09:16 INFO - 'MAIL': '/var/mail/cltbld', 17:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:16 INFO - 'MOZ_NO_REMOTE': '1', 17:09:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:16 INFO - 'NO_EM_RESTART': '1', 17:09:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:16 INFO - 'PWD': '/builds/slave/test', 17:09:16 INFO - 'SHELL': '/bin/bash', 17:09:16 INFO - 'SHLVL': '1', 17:09:16 INFO - 'TERM': 'linux', 17:09:16 INFO - 'TMOUT': '86400', 17:09:16 INFO - 'USER': 'cltbld', 17:09:16 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', 17:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:16 INFO - '_': '/tools/buildbot/bin/python'} 17:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:09:16 INFO - Running setup.py (path:/tmp/pip-IU3BC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:09:16 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)) 17:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:09:16 INFO - Running setup.py (path:/tmp/pip-j8DMIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:09:16 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)) 17:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:09:16 INFO - Running setup.py (path:/tmp/pip-8gn45D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:09:16 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)) 17:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:09:16 INFO - Running setup.py (path:/tmp/pip-sPwlvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:09:16 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)) 17:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:09:16 INFO - Running setup.py (path:/tmp/pip-jlkmy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:09:16 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)) 17:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:09:16 INFO - Running setup.py (path:/tmp/pip-YvBM53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:09:16 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)) 17:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:09:16 INFO - Running setup.py (path:/tmp/pip-QqzFuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:09:16 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)) 17:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:09:16 INFO - Running setup.py (path:/tmp/pip-NYsJwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:09:17 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)) 17:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:09:17 INFO - Running setup.py (path:/tmp/pip-O2b2cW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:09:17 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)) 17:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:09:17 INFO - Running setup.py (path:/tmp/pip-6gz8Ef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:09:17 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)) 17:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:09:17 INFO - Running setup.py (path:/tmp/pip-iZh8ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:09:17 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)) 17:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:09:17 INFO - Running setup.py (path:/tmp/pip-Yt6SBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:09:17 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)) 17:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:09:17 INFO - Running setup.py (path:/tmp/pip-klfg6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:09:17 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)) 17:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:09:17 INFO - Running setup.py (path:/tmp/pip-PSzBjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:09:17 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)) 17:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:17 INFO - Running setup.py (path:/tmp/pip-rCsQhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:17 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)) 17:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:09:17 INFO - Running setup.py (path:/tmp/pip-0hxcph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:09:17 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)) 17:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:09:17 INFO - Running setup.py (path:/tmp/pip-gk0g9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:09:17 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)) 17:09:17 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)) 17:09:17 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)) 17:09:17 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)) 17:09:17 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)) 17:09:17 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)) 17:09:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:09: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 17:09: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 17:09: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 17:09: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 17:09: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 17:09: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 17:09:19 INFO - Downloading blessings-1.6.tar.gz 17:09:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:09:19 INFO - Installing collected packages: blessings 17:09:19 INFO - Running setup.py install for blessings 17:09:19 INFO - Successfully installed blessings 17:09:19 INFO - Cleaning up... 17:09:19 INFO - Return code: 0 17:09:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:09:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:09:20 INFO - Reading from file tmpfile_stdout 17:09:20 INFO - Current package versions: 17:09:20 INFO - argparse == 1.2.1 17:09:20 INFO - blessings == 1.6 17:09:20 INFO - blobuploader == 1.2.4 17:09:20 INFO - docopt == 0.6.1 17:09:20 INFO - functools32 == 3.2.3-2 17:09:20 INFO - jsonschema == 2.5.1 17:09:20 INFO - manifestparser == 1.1 17:09:20 INFO - mozInstall == 1.12 17:09:20 INFO - mozcrash == 0.17 17:09:20 INFO - mozdebug == 0.1 17:09:20 INFO - mozdevice == 0.48 17:09:20 INFO - mozfile == 1.2 17:09:20 INFO - mozhttpd == 0.7 17:09:20 INFO - mozinfo == 0.9 17:09:20 INFO - mozleak == 0.1 17:09:20 INFO - mozlog == 3.3 17:09:20 INFO - moznetwork == 0.27 17:09:20 INFO - mozprocess == 0.23 17:09:20 INFO - mozprofile == 0.28 17:09:20 INFO - mozrunner == 6.12 17:09:20 INFO - mozscreenshot == 0.1 17:09:20 INFO - mozsystemmonitor == 0.3 17:09:20 INFO - moztest == 0.7 17:09:20 INFO - mozversion == 1.4 17:09:20 INFO - psutil == 3.1.1 17:09:20 INFO - requests == 1.2.3 17:09:20 INFO - wsgiref == 0.1.2 17:09:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:09: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')]} 17:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09: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')]} 17:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:20 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 0xc92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf513b0>, '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 0xf522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52740>, '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': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', '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 17:09:20 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 17:09:20 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 17:09:20 INFO - Using env: {'DISPLAY': ':0', 17:09:20 INFO - 'HOME': '/home/cltbld', 17:09:20 INFO - 'LANG': 'en_US.UTF-8', 17:09:20 INFO - 'LANGUAGE': 'en_US:en', 17:09:20 INFO - 'LOGNAME': 'cltbld', 17:09:20 INFO - 'MAIL': '/var/mail/cltbld', 17:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:20 INFO - 'MOZ_NO_REMOTE': '1', 17:09:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:20 INFO - 'NO_EM_RESTART': '1', 17:09:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:20 INFO - 'PWD': '/builds/slave/test', 17:09:20 INFO - 'SHELL': '/bin/bash', 17:09:20 INFO - 'SHLVL': '1', 17:09:20 INFO - 'TERM': 'linux', 17:09:20 INFO - 'TMOUT': '86400', 17:09:20 INFO - 'USER': 'cltbld', 17:09:20 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', 17:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:20 INFO - '_': '/tools/buildbot/bin/python'} 17:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:20 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)) 17:09:20 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)) 17:09:20 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)) 17:09:20 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)) 17:09:20 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)) 17:09:20 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)) 17:09:20 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)) 17:09:20 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)) 17:09:20 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)) 17:09:20 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)) 17:09:20 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)) 17:09:20 INFO - Cleaning up... 17:09:20 INFO - Return code: 0 17:09:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:09: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')]} 17:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09: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')]} 17:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf513b0>, '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 0xf522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52740>, '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': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', '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 17:09: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', 'jsonschema'] in /builds/slave/test/build 17:09: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 jsonschema 17:09:20 INFO - Using env: {'DISPLAY': ':0', 17:09:20 INFO - 'HOME': '/home/cltbld', 17:09:20 INFO - 'LANG': 'en_US.UTF-8', 17:09:20 INFO - 'LANGUAGE': 'en_US:en', 17:09:20 INFO - 'LOGNAME': 'cltbld', 17:09:20 INFO - 'MAIL': '/var/mail/cltbld', 17:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:20 INFO - 'MOZ_NO_REMOTE': '1', 17:09:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:20 INFO - 'NO_EM_RESTART': '1', 17:09:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:20 INFO - 'PWD': '/builds/slave/test', 17:09:20 INFO - 'SHELL': '/bin/bash', 17:09:20 INFO - 'SHLVL': '1', 17:09:20 INFO - 'TERM': 'linux', 17:09:20 INFO - 'TMOUT': '86400', 17:09:20 INFO - 'USER': 'cltbld', 17:09:20 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', 17:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:20 INFO - '_': '/tools/buildbot/bin/python'} 17:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:09:20 INFO - Cleaning up... 17:09:20 INFO - Return code: 0 17:09:20 INFO - Running post-action listener: _resource_record_post_action 17:09:20 INFO - Running post-action listener: _start_resource_monitoring 17:09:20 INFO - Starting resource monitoring. 17:09:20 INFO - [mozharness: 2016-10-04 00:09:20.574734Z] Finished create-virtualenv step (success) 17:09:20 INFO - [mozharness: 2016-10-04 00:09:20.575212Z] Running install step. 17:09:20 INFO - Running pre-action listener: _resource_record_pre_action 17:09:20 INFO - Running main action method: install 17:09:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:09:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:09:20 INFO - Reading from file tmpfile_stdout 17:09:20 INFO - Detecting whether we're running mozinstall >=1.0... 17:09:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:09:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:09:20 INFO - Reading from file tmpfile_stdout 17:09:20 INFO - Output received: 17:09:20 INFO - Usage: mozinstall [options] installer 17:09:20 INFO - Options: 17:09:20 INFO - -h, --help show this help message and exit 17:09:20 INFO - -d DEST, --destination=DEST 17:09:20 INFO - Directory to install application into. [default: 17:09:20 INFO - "/builds/slave/test"] 17:09:20 INFO - --app=APP Application being installed. [default: firefox] 17:09:20 INFO - mkdir: /builds/slave/test/build/application 17:09:20 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'] 17:09:20 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 17:09:34 INFO - Reading from file tmpfile_stdout 17:09:34 INFO - Output received: 17:09:34 INFO - /builds/slave/test/build/application/firefox/firefox 17:09:34 INFO - Running post-action listener: _resource_record_post_action 17:09:34 INFO - [mozharness: 2016-10-04 00:09:34.353109Z] Finished install step (success) 17:09:34 INFO - [mozharness: 2016-10-04 00:09:34.353457Z] Running run-tests step. 17:09:34 INFO - Running pre-action listener: _resource_record_pre_action 17:09:34 INFO - Running main action method: run_tests 17:09:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:09:34 INFO - Python 2.7.3 17:09:34 INFO - Return code: 0 17:09:34 INFO - grabbing minidump binary from tooltool 17:09:34 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')]} 17:09:34 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 0xf513b0>, '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 0xf522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:09:34 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 17:09:34 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 17:09:34 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 17:09:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:09:34 INFO - Return code: 0 17:09:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:09:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:09:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:09:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:09:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:09:34 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/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 17:09:34 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/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-024 --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 17:09:34 INFO - Using env: {'DISPLAY': ':0', 17:09:34 INFO - 'HOME': '/home/cltbld', 17:09:34 INFO - 'LANG': 'en_US.UTF-8', 17:09:34 INFO - 'LANGUAGE': 'en_US:en', 17:09:34 INFO - 'LOGNAME': 'cltbld', 17:09:34 INFO - 'MAIL': '/var/mail/cltbld', 17:09:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:09:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:34 INFO - 'MOZ_NO_REMOTE': '1', 17:09:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:09:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:34 INFO - 'NO_EM_RESTART': '1', 17:09:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:34 INFO - 'PWD': '/builds/slave/test', 17:09:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:09:34 INFO - 'SHELL': '/bin/bash', 17:09:34 INFO - 'SHLVL': '1', 17:09:34 INFO - 'TERM': 'linux', 17:09:34 INFO - 'TMOUT': '86400', 17:09:34 INFO - 'USER': 'cltbld', 17:09:34 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607', 17:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:34 INFO - '_': '/tools/buildbot/bin/python'} 17:09:34 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/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 17:09:34 INFO - mozversion application_buildid: 20161003141658 17:09:34 INFO - mozversion application_changeset: f3362d645d5e0c383f872775da6ad6adfaf339bb 17:09:34 INFO - mozversion application_display_name: Firefox 17:09:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:09:34 INFO - mozversion application_name: Firefox 17:09:34 INFO - mozversion application_remotingname: firefox 17:09:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:09:34 INFO - mozversion application_vendor: Mozilla 17:09:34 INFO - mozversion application_version: 50.0 17:09:34 INFO - mozversion platform_buildid: 20161003141658 17:09:34 INFO - mozversion platform_changeset: f3362d645d5e0c383f872775da6ad6adfaf339bb 17:09:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:09:34 INFO - mozversion platform_version: 50.0 17:09:34 INFO - using testdate: 1475539774 17:09:34 INFO - actual date: 1475539774 17:09:34 INFO - starting webserver on 'localhost:44566' 17:09:34 INFO - SUITE-START | Running 2 tests 17:09:34 INFO - TEST-START | dromaeo_css 17:09:34 INFO - operating with platform_type : linux_ 17:09:34 INFO - Initialising browser for dromaeo_css test... 17:09:34 INFO - TEST-INFO | started process 2019 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp17T7mz/profile http://localhost:44566/getInfo.html) 17:09:39 INFO - PROCESS | 2019 | __metrics Screen width/height:1600/1200 17:09:39 INFO - PROCESS | 2019 | colorDepth:24 17:09:39 INFO - PROCESS | 2019 | Browser inner width/height: 1024/697 17:09:39 INFO - PROCESS | 2019 | __metrics 17:09:39 INFO - PROCESS | 2019 | 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" 17:09:39 INFO - PROCESS | 2019 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:09:40 INFO - PROCESS | 2019 | console.error: PushService: 17:09:40 INFO - PROCESS | 2019 | stateChangeProcessEnqueue: Error transitioning state 17:09:40 INFO - PROCESS | 2019 | UnknownError 17:09:40 INFO - TEST-INFO | 2019: exit 0 17:09:40 INFO - Browser initialized. 17:09:40 INFO - Running cycle 1/1 for dromaeo_css test... 17:09:40 INFO - TEST-INFO | started process 2108 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp17T7mz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:09:52 INFO - PROCESS | 2108 | RSS: Main: 143048704 17:09:52 INFO - PROCESS | 2108 | 17:10:43 INFO - PROCESS | 2108 | 1475539843400 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] 17:12:52 INFO - PROCESS | 2108 | Cycle 1(1): loaded http://localhost:44566/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44566/tests/dromaeo/cssquery-ext.html) 17:12:53 INFO - PROCESS | 2108 | RSS: Main: 261607424 17:12:53 INFO - PROCESS | 2108 | 17:15:56 INFO - PROCESS | 2108 | Cycle 1(1): loaded http://localhost:44566/tests/dromaeo/cssquery-ext.html (next: http://localhost:44566/tests/dromaeo/cssquery-jquery.html) 17:15:56 INFO - PROCESS | 2108 | RSS: Main: 227459072 17:15:56 INFO - PROCESS | 2108 | 17:18:58 INFO - PROCESS | 2108 | Cycle 1(1): loaded http://localhost:44566/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44566/tests/dromaeo/cssquery-mootools.html) 17:18:59 INFO - PROCESS | 2108 | RSS: Main: 176545792 17:18:59 INFO - PROCESS | 2108 | 17:22:01 INFO - PROCESS | 2108 | Cycle 1(1): loaded http://localhost:44566/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44566/tests/dromaeo/cssquery-prototype.html) 17:22:01 INFO - PROCESS | 2108 | RSS: Main: 174182400 17:22:01 INFO - PROCESS | 2108 | 17:25:04 INFO - PROCESS | 2108 | Cycle 1(1): loaded http://localhost:44566/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44566/tests/dromaeo/cssquery-yui.html) 17:25:04 INFO - PROCESS | 2108 | RSS: Main: 173617152 17:25:04 INFO - PROCESS | 2108 | 17:28:07 INFO - PROCESS | 2108 | Cycle 1(1): loaded http://localhost:44566/tests/dromaeo/cssquery-yui.html (next: http://localhost:44566/tests/dromaeo/cssquery-dojo.html) 17:28:07 INFO - PROCESS | 2108 | RSS: Main: 265678848 17:28:07 INFO - PROCESS | 2108 | 17:28:07 INFO - PROCESS | 2108 | __start_tp_report 17:28:07 INFO - PROCESS | 2108 | _x_x_mozilla_page_load 17:28:07 INFO - PROCESS | 2108 | _x_x_mozilla_page_load_details 17:28:07 INFO - PROCESS | 2108 | |i|pagename|runs| 17:28:07 INFO - PROCESS | 2108 | |0;dojo.html;3227.5158726191075;3239.8380080995953;3247.090814571919;3250.317433338999;3281.73746100312;5548.528375088117;5631.57763167763;5644.153376993453;5665.320161580611;5688.402717714637;5681.517071048966;5764.538836893049;5767.913481297784;5768.307803063638;5772.134179873021;5217.58259339252;5230.607704422174;5243.9213411798855;5276.2085687146855;5287.841364759044;5085.084684756743;5085.618578606601;5314.867128321804;5318.9202161967405;5346.251524786533;5091.338126043609;5099.770510327045;5108.055009823176;5140.871478213037;5152.768125434346;5164.302819119408;5198.532132108128;5275.419703832576;5287.524122828964;5308.336457942757;5040.823571175028;5107.004134193834;5187.403448603383;5241.318628578299;5253.684778913239;5508.779648814043;5520.751566179491;5540.085885828834;5544.77820887164;5594.188842617838;5953.166556682068;6014.8195554133445;6056.909146362808;6066.302375226861;6071.453569178795;5877.559183061245;6004;6108.022716365404;6111.388861113897;6118.265808103011;5564.554835613141;5687.573431992617;5713.600047996601;5739.081746920473;5742.856428589277;6215.195800085661;6233.812985610439;6255.906161407583;6270.2789179244055;6270.623762574261;5780.075187969988;5872.823815285548;6008.849778755566;6018.15745795589;6018.879622407528;6083.855306140741;6089.69551522431;6111.35830737775;6124.632522048602;6131.386861313922;6049.697864108646;6050.062240352752;6053.303870055;6053.51571874249;6096;5844.1818145459665;5899.439553242435;6002.849928751729;6054.243219597551;6065.332813390523;4588.564086411786;4637.884052898705;4640.65195110368;4674.742889141062;4706.364640773451;4128.298189307825;4154.273002224599;4158.2307273154565;4183.288840897055;4183.97908010458;5640.7743690252755;5809.5352371811;5830.912536311958;5835.095560188178;5835.533157347487;5981.312149102821;6023.728932197974;6071.51427885768;6120.020512922583;6135.386461353918;5791.362950075489;5795.159701843293;5796.188533605298;5796.304443466811;5799.449052340021;5248.160294352885;5249.317588713443;5250.816221432232;5257.947420525746;5294.338207724035;6062.393760623902;6200.689965501707;6230.59501132425;6256.436920677161;6388.936110638834;6083.543734220039;6153.384661533811;6161.199044124414;6162.106494558174;6176.104464852481;6377.394147555975;6390.456811171102;6393.584417012879;6397.776077837253;6405.263394709668;5845.327787304344;5885.823425297248;5936.8812623748145;5947.821565353046;6053.3946605339115;6112.083187521729;6116.724747386467;6122.4795892347865;6123.663198524124;6126.877462450815;5928.140419639214;5978.372270911492;5980.5215582754145;5982.790602328898;5986.551008674454;5800.767969281182;5825.184474173534;5858.882822343615;5881.911771323519;5982.4017598239825;5861.35525092248;5863.296404431496;5866.149408335853;5866.266716660418;5880.794172203952;5908.409159084057;5933.554983376351;5965.313988891333;5986.042233242661;5993.310769261417;5696.629719068247;5731.312242530923;5753.71231438418;5784.971075144766;5804.912926306024 17:28:07 INFO - PROCESS | 2108 | |1;ext.html;594.0524862843763;638.9776357827476;670.7887015590087;678.1082876018036;692.2039654397439;35330.64669353309;46184.76907615461;46947.53052469475;46971;47069.76465117678;49655.75172124143;49856.50143498564;49994;50107.49892501074;50123.749381253045;8485.448445851;9312.487813170274;9445.197158241557;9460.526973651324;9462.668806591771;10817.783644327108;11151.330920144777;11177.72055698606;11530.769384612302;11536.077113830874;11410.144239182095;11525.36610486423;11539;11542.07663386927;11547.018503427218;11188.888111118866;11233.494492747846;11239.044681202107;11240.550377984871;11244.662660120128;11029.338239705643;11099;11104.722381940515;11104.722381940515;11105.278156919774;45008.099838003385;45442.31836522455;45492.090158196654;45495.772521137515;45565;3981.94027089591;4088.8160032798596;4120.484939382577;4271.508776490712;4349.478062632473;34041.14897127567;34948.47577286343;35068;35375;35954.64045359539;4605.217113090783;4674.859754207379;4697.084068606623;4702.6002789762915;4788.904221915543;380172;380503;382234;382636;383044;38318;62641.37358626447;62698;62915;63078;40648.79675601662;40705.38941915874;40890.79554602208;40909;40962.59037409647;17087.914560427365;17208.569785755204;17208.569785755455;17211.655766884593;17216;12116.636500904988;12987.41556629935;13284.601461956157;15066.472673456376;15154.545363638888;9259.212966897754;9264.629414823467;9271.629134834531;9326.067393260755;9362;3501.509788629594;3583.8387272572795;3607.0322645090714;3621.6197299283194;3640.8543658253902;948.4925564822787;1043.7755882485303;1056.9559865334622;1060.6499855047816;1115.263925808962;35052;35122.12194695102;35130.64869351325;35157.12107197289;35330.64669353273;1139.44817300522;1157.3403160198775;1181;1203.4283715235192;1213.8422005139275;18101.818981810276;18157.273709051755;18303.35938242155;18333.449996500127;18364;608363;612492;617421;617434;622257;63137.684311578145;63466.68266658637;63556;63563.68218158972;63780.68109659515;1282.4677758730022;1386.2652794019186;1413.22978976456;1413.2297897645808;1445.3712635003596;33962.49056264207;35391.646083538835;35566.64433355633;35594.82202589074;35793;54095;54731;54738.72630636821;54778.72610636921;54818.451815481334;414598;416576;417205;418053;418419;27187.86406067957;31221.37557248797;31345.373092537564;31708.682913171495;32793.34413311673;51573.48426515687;51647.22529161991;52222.73888630533;52473;52515.21227181519;43535.4483635595;44194.558054419045;44452.777736111115;45749.31376029296;46260.7686961563;43553.346699798894;43880;45238.77380613076;45844.54155458403;45912.08175836531;44046.11907761763;44889;44965.325520117876;46860.7656961713;47271.29093063538;39119.80440097781;40758.79620601878;41035.58964410437;41161.38257926074;41206.793966029974;30412.239694007825;30641.40378943087;30774.38451230918;30935.226619334695;30951 17:28:07 INFO - PROCESS | 2108 | |2;jquery.html;425.46179083459447;430.37196434060877;433.16615515133367;435.2752666809763;442.73214705103413;18519.81480185198;19862.801371986276;20346.491337716565;20719.171233150657;20899.68650470244;19263.940483273414;19403.4178974631;19669.114889829958;19863.900680496612;19984.20063197474;5912.4974377178;6142.508599312045;6143.385661433866;6146.38536146384;6146.569740118193;5991.071383935475;6018.638881667114;6057.273127224739;6078.6048906820915;6097.5426842986735;6013.646929440863;6015.127806468059;6017.217761690974;6020.096985452174;6020.96989515054;5958.702064896759;6028.5478589106;6080.787172448987;6081.969590152065;6083.999999999978;5284;5296.947030529684;5415.783368665248;5426.9185962210595;5427.294451721291;13886.583402497941;14823.77764333536;14844.628884277872;14855.108693478425;21022.789772102235;2332.183735692494;2468.962965555518;3037.179961410407;3138.6547479777205;3153.495440729472;14334.283285835667;14509.709805803825;14592.562223133324;14897.404103835834;15326.15706136162;1926.3450426854797;1957.5106223444138;1958.8237058664647;1959.1183967214668;1964.8329891959202;60333.69833150806;77727.61136194339;78491;82059;86980.13019869784;11405.201635885409;11463.197576169754;11544.307341559534;11692.181547291779;11988.220765650205;16364.018158910503;16524.339026439047;16710.749338759928;16777.245023973952;16786.664266714597;8586.270167035747;8921.509316987627;8959.283257339397;9045.954770226106;9217.76955576116;8749.212570868653;8798.38411311202;8800.295976321879;8981.685641002534;9003.684871029614;5002.324686167321;5049.899002019939;5092.694438333712;5119.8720031998755;5181.145111056709;1990.5919286546514;2062.3086871632663;2076.8234700050666;2098.655782530148;2101.3065688322818;991.4398364923795;1013.9188864890791;1018.3075508654039;1025.4462590201356;1046.2780681329857;19660;20533.2813351532;20544.897275513827;20581.691274630593;20793.854544909624;996.0437979539578;1032.0762917189224;1070.796548655753;1088.357868857378;1107.3300653104916;11042.889571104346;11390.715232119263;11437.142214333791;11637.59268425618;11687.532498700128;499559;502712;508223;524763;527675;91924;93719.53140234255;94606;98032;100905;985.2216748768393;1009.2228983682523;1016.5476363018386;1029.711680729397;1031.63377001165;17260.654786904444;17939.910300448413;18116.63766724632;18369.816301837345;18497.53756156107;41629;42470.5752942479;42569.23299985491;42637.786811065744;43226;55867.72066139643;59988.10017849648;60526.394736052076;61226;61467.69266153641;8001.799955001172;8419.284360829153;8731.738047858575;8946;9130.04134565861;12727.363631818187;13223.735525289248;13713.588592341846;13783.104098233583;14065.929670351583;11647;11869.347185904859;12211.328376939353;12325.260484370967;12382.195157314745;10460.424676642857;11577.305361677989;11867.643970680894;12161.270323780602;12226.388680565758;11504.36725980079;11984.247561170861;12248.326342051272;12529.624111276677;12706.745865082832;8817.338699597427;9056.366054376394;9079.092090790868;9201.539923003957;9457;9167.609374922113;11769.588064417705;11800.76398472043;12115.878841211828;12453 17:28:07 INFO - PROCESS | 2108 | |3;mootools.html;1431.8997670163092;1538.8384219656932;1549.1015211177519;1719.9484015479532;1841.8894866308021;8993.145651163137;9407.764805879848;9449.574769135388;9729.124378805906;9746.756331091727;9557.856632150506;9658.275629327793;9680.080392362714;9682.903170968288;9688.806223875536;2451.6690246816675;2493.613489909067;2494.052260141153;2494.3514686181543;2498.812589055819;2466.2847774145475;2538.2385284414695;2543.135333986444;2545.2491515003026;2548.7068987066446;2486.751324867517;2495.8502489850575;2544.2875994721417;2549.1970029440668;2550.1966880432697;2431.234161239204;2452.730199678034;2461.692288463942;2462.4336402627496;2463.790577800889;2428.453597940467;2449.326435230308;2457.017193122765;2459.8524088554746;2476.4675594747214;9587.089226523469;9644.469554174517;9675.612975480972;9738.123568878835;9755.951220243924;5476.205950137248;5728.856778580527;5738.7991420300505;5739.885202295973;5743.16724075011;3714.7771133732062;3788.829502672359;3832.6550610445192;3840.519935008124;3856.2865869814173;1182.4442512019423;1183.5206741269799;1189.6550000499844;1217.8903898649075;1238.7150955280247;13740.312984350743;13821.378037988316;13878.236696981661;13886.09740366883;13909.58271251864;4757.714537127714;4811.109944660249;4832.613390928717;4889.779959901813;4969.3291405659775;10768.192385571114;10800.675979720621;10804.67585972422;10804.729881753019;10805.081568066646;5560.944390556043;5561.972190139023;5562.221289019541;5564.33228012641;5565.888682226333;4663.463701674283;4703.412073490814;5152.381714194245;5201.635885487979;5207.9844430335725;4175.91648167035;4178.310178201377;4224.978875105605;4255.404243405926;4264.530901600821;1181.3502573584494;1195.970100747471;1197.26368283505;1206.324458303353;1221.407617305606;1029.7888932768765;1043.2853495355705;1050.2858056521486;1051.7738686182354;1057.2282233969245;6173.506119510428;6223;6400.455961243347;6538.8365290868105;6790.660466976631;485.2599785327404;489.3858207949001;492.308306828906;495.1285737102725;496.2407516499761;4260.573942605776;4318.438602981655;4326.459192600925;4326.6754993375625;4372.737635741865;11958.342291174069;12395.690107747378;12407.875921240671;12414.689632759253;12432.689182770322;12317.07621928359;12352.135350525377;12383.318917459446;12389.256644601353;12429.254244745343;8626.223639872442;9010.819783604291;9042.321825862955;9139.086091390807;9242.168204861595;6534.738610455529;6605.240397354366;6624.966875165594;6819.318068193141;6894.345037221657;10929.508172132115;11541.711457213636;11562.537498500302;11626.941865290957;11632.767344653228;10314.484275786332;10854.511546980248;11459.312441253218;11680.649580512598;11893.286402815858;8380.497170169809;8711.47731136134;8749.693760718344;8827.602757875782;8902.688405905763;9495.335326527076;9642.276829237695;9646.614135434504;9658.275629327689;9660.420374777535;8534.914650853412;8593.65625374978;8684.478931263891;8715.694950676696;8721.258693011165;8375.58122093905;8376.790580235544;8397;8461.365397595082;8471;7732.30409263169;7854.096778870503;7916.881246781188;7921.128675845767;7952.403569732178;5423.701696406735;5489.917651235155;5575.386707462257;5578.888422231452;5631.7184140793615;5664.971675141598;5666.376698563237;5673.602847800615;5700.914486282626;5805.506531944832 17:28:07 INFO - PROCESS | 2108 | |4;prototype.html;352.73721077797035;352.96646818552244;354.3108653668614;365.8024666679991;366.55646667532284;5524.530414914732;5540.534462164338;5550.862073274979;5552.639078459897;5572.219889215513;5462.562994960404;5472.522418946882;5491.258680078188;5519.282493275879;5538.224648549206;4250.171216612754;4406.272964960794;4418.923106140109;4426.380306757057;4443.88890277744;4367.323064924942;4373.868783936487;4378.6934914555995;4383.9342409863875;4409.338599210112;4166.708330416872;4359.149965756678;4371.803268852894;4401.207782599147;4412.735235885857;4141.958580414187;4342.5874541918465;4351.6518678505645;4376.6936314458;4396.648268138573;3910.3156947534067;3921.450996860442;3921.941170882439;3924.666403355718;3927.4501569780255;5236.6857988520815;5421.322334708161;5443.129099344125;5456.781728730846;5458.85364073545;4943.6539442239055;4947.158982972904;4966.2798894160505;4988.127077761414;5003.774830132653;5695.572832037614;5721.513671337942;5730.34101078373;5754.712264386764;5779.219805326311;4092.386142078712;4252.702310838243;4257.425247591566;4268.0823622921225;4287.892802679958;5954.910676339815;5959.940400596025;5968.7015649217365;5977.7608895644125;5980.491658209101;4409.492908315585;4413.6248418884115;4415.933760993587;4430.892276930767;4451.710638808467;4420.270655341833;4450.532694067077;4524.615407690318;4525.456945166601;4537.0925814837165;4974.9303899661745;4984.675996060245;4992.700437973734;4994.625403094782;4997.275395067695;5743.971280143656;5781.19063331134;5788.623739456922;5789.350035239936;5817.621854579439;1473.8157730283715;1539.7921280626972;1549.9535013949599;1550.6511035017031;1557.6728886933645;998.0618218874235;1007.3603261928728;1015.2284263959297;1031.5976769060073;1040.625374865048;4921.252955200852;4947.293183851565;4967.379077615298;4970.726610036482;4971.45314015458;5147.485251474823;5161.458046905096;5169.508896654812;5187.58499320061;5198.570393141917;4983.352164218701;5394.649347792381;5488.313960754906;5560.805371811967;5632.53554075935;3083.8766449342224;3119.656837747846;3124.656287808339;3138.905832825019;3160.541721450377;5899.557533185029;6090.23872015998;6105.175801266861;6113.174721412553;6152.261728592597;6064.3935606439;6064.423879731418;6070.994857545543;6078.726457309432;6108.908366374508;6189.700162965828;6190.845228869134;6198.256209254918;6238.500919926395;6251.562390632703;5713.600047996767;5894.410558944071;5932.080527518242;5964.642121472725;5969.403059693996;5898.64608123514;5907.81843631284;5911.349751527415;5945.375735547878;5960.254968128984;5936.970315148396;6167.84580385494;6176.227971503562;6215.595986260879;6244.2819075806865;4291.549387314287;4308.332208507686;4326.351047342924;4358.433403657505;4360.514506731133;5975.820725378246;5978.850528736816;6022.789202377896;6063.696815159136;6067.575269731076;5719.942800571941;5786.276715410574;5810.854728631818;5819.912701309399;5823.8835223294445;5766.654000759968;5804.593678442468;5810.73851676684;5812.94187058141;5902.173695682522;5575.804846830341;5784.843031393654;5824.9708751457665;5864.765409383577;5873.2658417697785;5845.503132233808;5874.324452687824;5874.94125058744;5880.765309182654;5887.146363777314;5748.425157484218;5761.222234998221;5761.596688231783;5847.473727364558;5856.736446859816 17:28:07 INFO - PROCESS | 2108 | |5;yui.html;817.7955511122219;848.561687938943;861.7802460372604;875.7766769473783;928.3130483442251;15956;17517.299308027694;17552.385666501676;17569.385071522498;17643.11784410779;16206.513804585882;16413.343466261336;16418.589535261624;16491.09298988555;16496.587585310343;718.1669263654162;834.4284165346727;835.1940377535677;844.1642773654099;848.6053984897021;862.180928118287;870.8954925408959;871.0766587417357;871.960761765722;872.5549969515529;926.5043201886991;928.1924725488834;928.2898582584328;928.7306681062483;929.0152438415302;917.3807679816131;924.1405492891608;925.4169872980046;925.7593025813337;926.0878035135386;673.293042305582;676.8375589858399;677.5900580148988;678.1726293921408;678.4504551313403;16339.18304084803;16459.753103703453;16476;16509.66980660392;16535.83464165355;16112.838871611368;16829;16856.747148792656;17177.141142942804;17685.734713979302;13319.334033298297;13673.314518968931;13719.314034298246;13779.448822047107;13929.164250144922;4051.351783714621;4077.4495443115393;4089.161721847014;4105.44576482174;4225.894352641178;68351.316486835;70716.64641676862;84443;84610.57694711487;85667.57166214129;12320.507179712891;12699.809502857464;12735.299558524184;12735.299558524184;12738.426770795151;22090.33728988133;22280.442988925406;22377.216797412013;22482.66276005861;22504.43738906508;9217.44695318283;9353.204977576846;9484.573194206278;9506.714798556055;9709.611615535441;4858.73276969763;5023.472535383805;5107.412647545506;5162.845114646567;5206.140986737221;3796.449514820391;3912.960870391317;3945.349017412152;3945.6646185074014;3946.2304850553865;682.222266616058;683.6991723641581;703.63762662229;714.8963400306926;718.0306586108816;588.1207594646031;628.9433950944436;690.3683129936153;714.078838298589;745.2137994415835;10060.74848128788;10099.747506312255;10536.15710743154;10621.575136994588;10881.510332035077;705.2594775485719;712.5855177472713;729.9708011679579;754.3964828137468;781.1407451803085;4985.9115525268935;5032.47159048302;5100.770465329069;5142.562882155059;5161.225816127611;73565.26434735692;74232.62883685654;74232.62883685654;101541;111856;118215;122059.38970305269;122375;122758.99999999821;123541;99821.50089249652;104875.47562262292;105310.47344763228;105344.99999999847;106999;9494.90505094968;9516.38143520669;9746.951265243628;10082.546285417027;10102.494875256061;63065.684671576346;63599;64312.67843660939;64353.678231608545;65899.34100659123;89420.32365800183;95066.52466737622;95300;96407;98252;12565.246085234916;12670.556530521388;12737.617871463872;12801.359932003548;12987.35063246815;13630.727385452037;13655.248961306826;14345.928270358581;14489.927550362181;14836.554903352917;13563.525276615272;13698.38357273905;14481.710365792836;14982.325795339018;15041.323140458924;12081.69795755078;12672.429740661508;12720.554780582635;12976.545820896223;13518.391672374963;12893.613191604267;12935.870641293843;13017.544385946447;13719.245441500812;13785.724285514434;9779.951100244738;10230.795384092426;10458.111060559937;10530.736731581464;11047.502862371373;11227.270227435518;11254.155938304497;11378.37418941933;11402.48688808989;11441.199116061796 17:28:07 INFO - PROCESS | 2108 | __end_tp_report 17:28:07 INFO - PROCESS | 2108 | __start_cc_report 17:28:07 INFO - PROCESS | 2108 | _x_x_mozilla_cycle_collect,991 17:28:07 INFO - PROCESS | 2108 | __end_cc_report 17:28:07 INFO - PROCESS | 2108 | __startTimestamp1475540887586__endTimestamp 17:28:07 INFO - PROCESS | 2108 | 17:28:07 INFO - PROCESS | 2108 | ------- Summary: start ------- 17:28:07 INFO - PROCESS | 2108 | Number of tests: 6 17:28:07 INFO - PROCESS | 2108 | 17:28:07 INFO - PROCESS | 2108 | [#0] dojo.html Cycles:165 Average:5644.04 Median:5835.53 stddev:640.30 (11.0%) stddev-sans-first:613.55 17:28:07 INFO - PROCESS | 2108 | Values: 3227.5 3239.8 3247.1 3250.3 3281.7 5548.5 5631.6 5644.2 5665.3 5688.4 5681.5 5764.5 5767.9 5768.3 5772.1 5217.6 5230.6 5243.9 5276.2 5287.8 5085.1 5085.6 5314.9 5318.9 5346.3 5091.3 5099.8 5108.1 5140.9 5152.8 5164.3 5198.5 5275.4 5287.5 5308.3 5040.8 5107.0 5187.4 5241.3 5253.7 5508.8 5520.8 5540.1 5544.8 5594.2 5953.2 6014.8 6056.9 6066.3 6071.5 5877.6 6004.0 6108.0 6111.4 6118.3 5564.6 5687.6 5713.6 5739.1 5742.9 6215.2 6233.8 6255.9 6270.3 6270.6 5780.1 5872.8 6008.8 6018.2 6018.9 6083.9 6089.7 6111.4 6124.6 6131.4 6049.7 6050.1 6053.3 6053.5 6096.0 5844.2 5899.4 6002.8 6054.2 6065.3 4588.6 4637.9 4640.7 4674.7 4706.4 4128.3 4154.3 4158.2 4183.3 4184.0 5640.8 5809.5 5830.9 5835.1 5835.5 5981.3 6023.7 6071.5 6120.0 6135.4 5791.4 5795.2 5796.2 5796.3 5799.4 5248.2 5249.3 5250.8 5257.9 5294.3 6062.4 6200.7 6230.6 6256.4 6388.9 6083.5 6153.4 6161.2 6162.1 6176.1 6377.4 6390.5 6393.6 6397.8 6405.3 5845.3 5885.8 5936.9 5947.8 6053.4 6112.1 6116.7 6122.5 6123.7 6126.9 5928.1 5978.4 5980.5 5982.8 5986.6 5800.8 5825.2 5858.9 5881.9 5982.4 5861.4 5863.3 5866.1 5866.3 5880.8 5908.4 5933.6 5965.3 5986.0 5993.3 5696.6 5731.3 5753.7 5785.0 5804.9 17:28:07 INFO - PROCESS | 2108 | 17:28:07 INFO - PROCESS | 2108 | [#1] ext.html Cycles:180 Average:63768.35 Median:22423.81 stddev:128212.74 (571.8%) stddev-sans-first:128484.68 17:28:07 INFO - PROCESS | 2108 | Values: 594.1 639.0 670.8 678.1 692.2 35330.6 46184.8 46947.5 46971.0 47069.8 49655.8 49856.5 49994.0 50107.5 50123.7 8485.4 9312.5 9445.2 9460.5 9462.7 10817.8 11151.3 11177.7 11530.8 11536.1 11410.1 11525.4 11539.0 11542.1 11547.0 11188.9 11233.5 11239.0 11240.6 11244.7 11029.3 11099.0 11104.7 11104.7 11105.3 45008.1 45442.3 45492.1 45495.8 45565.0 3981.9 4088.8 4120.5 4271.5 4349.5 34041.1 34948.5 35068.0 35375.0 35954.6 4605.2 4674.9 4697.1 4702.6 4788.9 380172.0 380503.0 382234.0 382636.0 383044.0 38318.0 62641.4 62698.0 62915.0 63078.0 40648.8 40705.4 40890.8 40909.0 40962.6 17087.9 17208.6 17208.6 17211.7 17216.0 12116.6 12987.4 13284.6 15066.5 15154.5 9259.2 9264.6 9271.6 9326.1 9362.0 3501.5 3583.8 3607.0 3621.6 3640.9 948.5 1043.8 1057.0 1060.6 1115.3 35052.0 35122.1 35130.6 35157.1 35330.6 1139.4 1157.3 1181.0 1203.4 1213.8 18101.8 18157.3 18303.4 18333.4 18364.0 608363.0 612492.0 617421.0 617434.0 622257.0 63137.7 63466.7 63556.0 63563.7 63780.7 1282.5 1386.3 1413.2 1413.2 1445.4 33962.5 35391.6 35566.6 35594.8 35793.0 54095.0 54731.0 54738.7 54778.7 54818.5 414598.0 416576.0 417205.0 418053.0 418419.0 27187.9 31221.4 31345.4 31708.7 32793.3 51573.5 51647.2 52222.7 52473.0 52515.2 43535.4 44194.6 44452.8 45749.3 46260.8 43553.3 43880.0 45238.8 45844.5 45912.1 44046.1 44889.0 44965.3 46860.8 47271.3 39119.8 40758.8 41035.6 41161.4 41206.8 30412.2 30641.4 30774.4 30935.2 30951.0 17:28:07 INFO - PROCESS | 2108 | 17:28:07 INFO - PROCESS | 2108 | [#2] jquery.html Cycles:180 Average:29940.72 Median:11562.05 stddev:84406.09 (730.0%) stddev-sans-first:84613.77 17:28:07 INFO - PROCESS | 2108 | Values: 425.5 430.4 433.2 435.3 442.7 18519.8 19862.8 20346.5 20719.2 20899.7 19263.9 19403.4 19669.1 19863.9 19984.2 5912.5 6142.5 6143.4 6146.4 6146.6 5991.1 6018.6 6057.3 6078.6 6097.5 6013.6 6015.1 6017.2 6020.1 6021.0 5958.7 6028.5 6080.8 6082.0 6084.0 5284.0 5296.9 5415.8 5426.9 5427.3 13886.6 14823.8 14844.6 14855.1 21022.8 2332.2 2469.0 3037.2 3138.7 3153.5 14334.3 14509.7 14592.6 14897.4 15326.2 1926.3 1957.5 1958.8 1959.1 1964.8 60333.7 77727.6 78491.0 82059.0 86980.1 11405.2 11463.2 11544.3 11692.2 11988.2 16364.0 16524.3 16710.7 16777.2 16786.7 8586.3 8921.5 8959.3 9046.0 9217.8 8749.2 8798.4 8800.3 8981.7 9003.7 5002.3 5049.9 5092.7 5119.9 5181.1 1990.6 2062.3 2076.8 2098.7 2101.3 991.4 1013.9 1018.3 1025.4 1046.3 19660.0 20533.3 20544.9 20581.7 20793.9 996.0 1032.1 1070.8 1088.4 1107.3 11042.9 11390.7 11437.1 11637.6 11687.5 499559.0 502712.0 508223.0 524763.0 527675.0 91924.0 93719.5 94606.0 98032.0 100905.0 985.2 1009.2 1016.5 1029.7 1031.6 17260.7 17939.9 18116.6 18369.8 18497.5 41629.0 42470.6 42569.2 42637.8 43226.0 55867.7 59988.1 60526.4 61226.0 61467.7 8001.8 8419.3 8731.7 8946.0 9130.0 12727.4 13223.7 13713.6 13783.1 14065.9 11647.0 11869.3 12211.3 12325.3 12382.2 10460.4 11577.3 11867.6 12161.3 12226.4 11504.4 11984.2 12248.3 12529.6 12706.7 8817.3 9056.4 9079.1 9201.5 9457.0 9167.6 11769.6 11800.8 12115.9 12453.0 17:28:07 INFO - PROCESS | 2108 | 17:28:07 INFO - PROCESS | 2108 | [#3] mootools.html Cycles:180 Average:6359.17 Median:4683.44 stddev:3735.66 (79.8%) stddev-sans-first:3727.78 17:28:07 INFO - PROCESS | 2108 | Values: 1431.9 1538.8 1549.1 1719.9 1841.9 8993.1 9407.8 9449.6 9729.1 9746.8 9557.9 9658.3 9680.1 9682.9 9688.8 2451.7 2493.6 2494.1 2494.4 2498.8 2466.3 2538.2 2543.1 2545.2 2548.7 2486.8 2495.9 2544.3 2549.2 2550.2 2431.2 2452.7 2461.7 2462.4 2463.8 2428.5 2449.3 2457.0 2459.9 2476.5 9587.1 9644.5 9675.6 9738.1 9756.0 5476.2 5728.9 5738.8 5739.9 5743.2 3714.8 3788.8 3832.7 3840.5 3856.3 1182.4 1183.5 1189.7 1217.9 1238.7 13740.3 13821.4 13878.2 13886.1 13909.6 4757.7 4811.1 4832.6 4889.8 4969.3 10768.2 10800.7 10804.7 10804.7 10805.1 5560.9 5562.0 5562.2 5564.3 5565.9 4663.5 4703.4 5152.4 5201.6 5208.0 4175.9 4178.3 4225.0 4255.4 4264.5 1181.4 1196.0 1197.3 1206.3 1221.4 1029.8 1043.3 1050.3 1051.8 1057.2 6173.5 6223.0 6400.5 6538.8 6790.7 485.3 489.4 492.3 495.1 496.2 4260.6 4318.4 4326.5 4326.7 4372.7 11958.3 12395.7 12407.9 12414.7 12432.7 12317.1 12352.1 12383.3 12389.3 12429.3 8626.2 9010.8 9042.3 9139.1 9242.2 6534.7 6605.2 6625.0 6819.3 6894.3 10929.5 11541.7 11562.5 11626.9 11632.8 10314.5 10854.5 11459.3 11680.6 11893.3 8380.5 8711.5 8749.7 8827.6 8902.7 9495.3 9642.3 9646.6 9658.3 9660.4 8534.9 8593.7 8684.5 8715.7 8721.3 8375.6 8376.8 8397.0 8461.4 8471.0 7732.3 7854.1 7916.9 7921.1 7952.4 5423.7 5489.9 5575.4 5578.9 5631.7 5665.0 5666.4 5673.6 5700.9 5805.5 17:28:07 INFO - PROCESS | 2108 | 17:28:07 INFO - PROCESS | 2108 | [#4] prototype.html Cycles:180 Average:4881.21 Median:5460.71 stddev:1409.41 (25.8%) stddev-sans-first:1371.77 17:28:07 INFO - PROCESS | 2108 | Values: 352.7 353.0 354.3 365.8 366.6 5524.5 5540.5 5550.9 5552.6 5572.2 5462.6 5472.5 5491.3 5519.3 5538.2 4250.2 4406.3 4418.9 4426.4 4443.9 4367.3 4373.9 4378.7 4383.9 4409.3 4166.7 4359.1 4371.8 4401.2 4412.7 4142.0 4342.6 4351.7 4376.7 4396.6 3910.3 3921.5 3921.9 3924.7 3927.5 5236.7 5421.3 5443.1 5456.8 5458.9 4943.7 4947.2 4966.3 4988.1 5003.8 5695.6 5721.5 5730.3 5754.7 5779.2 4092.4 4252.7 4257.4 4268.1 4287.9 5954.9 5959.9 5968.7 5977.8 5980.5 4409.5 4413.6 4415.9 4430.9 4451.7 4420.3 4450.5 4524.6 4525.5 4537.1 4974.9 4984.7 4992.7 4994.6 4997.3 5744.0 5781.2 5788.6 5789.4 5817.6 1473.8 1539.8 1550.0 1550.7 1557.7 998.1 1007.4 1015.2 1031.6 1040.6 4921.3 4947.3 4967.4 4970.7 4971.5 5147.5 5161.5 5169.5 5187.6 5198.6 4983.4 5394.6 5488.3 5560.8 5632.5 3083.9 3119.7 3124.7 3138.9 3160.5 5899.6 6090.2 6105.2 6113.2 6152.3 6064.4 6064.4 6071.0 6078.7 6108.9 6189.7 6190.8 6198.3 6238.5 6251.6 5713.6 5894.4 5932.1 5964.6 5969.4 5898.6 5907.8 5911.3 5945.4 5960.3 5937.0 6167.8 6176.2 6215.6 6244.3 4291.5 4308.3 4326.4 4358.4 4360.5 5975.8 5978.9 6022.8 6063.7 6067.6 5719.9 5786.3 5810.9 5819.9 5823.9 5766.7 5804.6 5810.7 5812.9 5902.2 5575.8 5784.8 5825.0 5864.8 5873.3 5845.5 5874.3 5874.9 5880.8 5887.1 5748.4 5761.2 5761.6 5847.5 5856.7 17:28:07 INFO - PROCESS | 2108 | 17:28:07 INFO - PROCESS | 2108 | [#5] yui.html Cycles:180 Average:22531.30 Median:3945.95 stddev:32681.22 (828.2%) stddev-sans-first:32732.23 17:28:07 INFO - PROCESS | 2108 | Values: 817.8 848.6 861.8 875.8 928.3 15956.0 17517.3 17552.4 17569.4 17643.1 16206.5 16413.3 16418.6 16491.1 16496.6 718.2 834.4 835.2 844.2 848.6 862.2 870.9 871.1 872.0 872.6 926.5 928.2 928.3 928.7 929.0 917.4 924.1 925.4 925.8 926.1 673.3 676.8 677.6 678.2 678.5 16339.2 16459.8 16476.0 16509.7 16535.8 16112.8 16829.0 16856.7 17177.1 17685.7 13319.3 13673.3 13719.3 13779.4 13929.2 4051.4 4077.4 4089.2 4105.4 4225.9 68351.3 70716.6 84443.0 84610.6 85667.6 12320.5 12699.8 12735.3 12735.3 12738.4 22090.3 22280.4 22377.2 22482.7 22504.4 9217.4 9353.2 9484.6 9506.7 9709.6 4858.7 5023.5 5107.4 5162.8 5206.1 3796.4 3913.0 3945.3 3945.7 3946.2 682.2 683.7 703.6 714.9 718.0 588.1 628.9 690.4 714.1 745.2 10060.7 10099.7 10536.2 10621.6 10881.5 705.3 712.6 730.0 754.4 781.1 4985.9 5032.5 5100.8 5142.6 5161.2 73565.3 74232.6 74232.6 101541.0 111856.0 118215.0 122059.4 122375.0 122759.0 123541.0 99821.5 104875.5 105310.5 105345.0 106999.0 9494.9 9516.4 9747.0 10082.5 10102.5 63065.7 63599.0 64312.7 64353.7 65899.3 89420.3 95066.5 95300.0 96407.0 98252.0 12565.2 12670.6 12737.6 12801.4 12987.4 13630.7 13655.2 14345.9 14489.9 14836.6 13563.5 13698.4 14481.7 14982.3 15041.3 12081.7 12672.4 12720.6 12976.5 13518.4 12893.6 12935.9 13017.5 13719.2 13785.7 9780.0 10230.8 10458.1 10530.7 11047.5 11227.3 11254.2 11378.4 11402.5 11441.2 17:28:07 INFO - PROCESS | 2108 | -------- Summary: end -------- 17:28:07 INFO - PROCESS | 2108 | 17:28:08 INFO - TEST-INFO | 2108: exit 0 17:28:08 INFO - TEST-OK | dromaeo_css | took 1113720ms 17:28:08 INFO - TEST-START | kraken 17:28:08 INFO - operating with platform_type : linux_ 17:28:08 INFO - Initialising browser for kraken test... 17:28:08 INFO - TEST-INFO | started process 2304 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKal2M0/profile http://localhost:44566/getInfo.html) 17:28:12 INFO - PROCESS | 2304 | __metrics Screen width/height:1600/1200 17:28:12 INFO - PROCESS | 2304 | colorDepth:24 17:28:12 INFO - PROCESS | 2304 | Browser inner width/height: 1024/697 17:28:12 INFO - PROCESS | 2304 | __metrics 17:28:13 INFO - PROCESS | 2304 | 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" 17:28:13 INFO - PROCESS | 2304 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:28:14 INFO - TEST-INFO | 2304: exit 0 17:28:14 INFO - Browser initialized. 17:28:14 INFO - Running cycle 1/1 for kraken test... 17:28:14 INFO - TEST-INFO | started process 2464 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKal2M0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:28:25 INFO - PROCESS | 2464 | RSS: Main: 143798272 17:28:25 INFO - PROCESS | 2464 | 17:28:28 INFO - PROCESS | 2464 | Cycle 1(1): loaded http://localhost:44566/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44566/tests/kraken/driver.html?testName=audio-beat-detection) 17:28:29 INFO - PROCESS | 2464 | RSS: Main: 198979584 17:28:29 INFO - PROCESS | 2464 | 17:28:31 INFO - PROCESS | 2464 | Cycle 1(1): loaded http://localhost:44566/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44566/tests/kraken/driver.html?testName=audio-dft) 17:28:32 INFO - PROCESS | 2464 | RSS: Main: 183173120 17:28:32 INFO - PROCESS | 2464 | 17:28:35 INFO - PROCESS | 2464 | Cycle 1(1): loaded http://localhost:44566/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44566/tests/kraken/driver.html?testName=audio-fft) 17:28:35 INFO - PROCESS | 2464 | RSS: Main: 273661952 17:28:35 INFO - PROCESS | 2464 | 17:28:37 INFO - PROCESS | 2464 | Cycle 1(1): loaded http://localhost:44566/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44566/tests/kraken/driver.html?testName=audio-oscillator) 17:28:38 INFO - PROCESS | 2464 | RSS: Main: 179449856 17:28:38 INFO - PROCESS | 2464 | 17:28:40 INFO - PROCESS | 2464 | Cycle 1(1): loaded http://localhost:44566/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44566/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:28:41 INFO - PROCESS | 2464 | RSS: Main: 178216960 17:28:41 INFO - PROCESS | 2464 | 17:28:44 INFO - PROCESS | 2464 | Cycle 1(1): loaded http://localhost:44566/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44566/tests/kraken/driver.html?testName=imaging-darkroom) 17:28:44 INFO - PROCESS | 2464 | RSS: Main: 214740992 17:28:44 INFO - PROCESS | 2464 | 17:28:48 INFO - PROCESS | 2464 | Cycle 1(1): loaded http://localhost:44566/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44566/tests/kraken/driver.html?testName=imaging-desaturate) 17:28:48 INFO - PROCESS | 2464 | RSS: Main: 213794816 17:28:48 INFO - PROCESS | 2464 | 17:28:51 INFO - PROCESS | 2464 | Cycle 1(1): loaded http://localhost:44566/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44566/tests/kraken/driver.html?testName=json-parse-financial) 17:28:52 INFO - PROCESS | 2464 | RSS: Main: 213778432 17:28:52 INFO - PROCESS | 2464 | 17:28:53 INFO - PROCESS | 2464 | Cycle 1(1): loaded http://localhost:44566/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44566/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:28:54 INFO - PROCESS | 2464 | RSS: Main: 175878144 17:28:54 INFO - PROCESS | 2464 | 17:28:56 INFO - PROCESS | 2464 | Cycle 1(1): loaded http://localhost:44566/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44566/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:28:56 INFO - PROCESS | 2464 | RSS: Main: 182476800 17:28:56 INFO - PROCESS | 2464 | 17:28:58 INFO - PROCESS | 2464 | Cycle 1(1): loaded http://localhost:44566/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44566/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:28:59 INFO - PROCESS | 2464 | RSS: Main: 184446976 17:28:59 INFO - PROCESS | 2464 | 17:29:01 INFO - PROCESS | 2464 | Cycle 1(1): loaded http://localhost:44566/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44566/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:29:02 INFO - PROCESS | 2464 | RSS: Main: 185335808 17:29:02 INFO - PROCESS | 2464 | 17:29:04 INFO - PROCESS | 2464 | Cycle 1(1): loaded http://localhost:44566/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44566/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:29:05 INFO - PROCESS | 2464 | RSS: Main: 182603776 17:29:05 INFO - PROCESS | 2464 | 17:29:07 INFO - PROCESS | 2464 | Cycle 1(1): loaded http://localhost:44566/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44566/tests/kraken/driver.html?testName=ai-astar) 17:29:07 INFO - PROCESS | 2464 | RSS: Main: 233312256 17:29:07 INFO - PROCESS | 2464 | 17:29:07 INFO - PROCESS | 2464 | __start_tp_report 17:29:07 INFO - PROCESS | 2464 | _x_x_mozilla_page_load 17:29:07 INFO - PROCESS | 2464 | _x_x_mozilla_page_load_details 17:29:07 INFO - PROCESS | 2464 | |i|pagename|runs| 17:29:07 INFO - PROCESS | 2464 | |0;ai-astar;113;113;121;111;111;111;110;111;111;112 17:29:07 INFO - PROCESS | 2464 | |1;audio-beat-detection;147;118;152;178;116;145;140;117;173;120 17:29:07 INFO - PROCESS | 2464 | |2;audio-dft;161;161;160;172;160;161;160;160;163;161 17:29:07 INFO - PROCESS | 2464 | |3;audio-fft;75;75;75;178;75;75;76;75;187;75 17:29:07 INFO - PROCESS | 2464 | |4;audio-oscillator;84;82;170;82;82;84;143;83;83;161 17:29:07 INFO - PROCESS | 2464 | |5;imaging-gaussian-blur;117;117;116;116;117;117;117;116;117;117 17:29:07 INFO - PROCESS | 2464 | |6;imaging-darkroom;146;147;145;146;146;146;146;147;145;145 17:29:07 INFO - PROCESS | 2464 | |7;imaging-desaturate;94;95;95;94;95;95;95;95;95;95 17:29:07 INFO - PROCESS | 2464 | |8;json-parse-financial;49;50;49;50;51;57;51;50;49;48 17:29:07 INFO - PROCESS | 2464 | |9;json-stringify-tinderbox;60;60;59;60;61;60;59;61;60;61 17:29:07 INFO - PROCESS | 2464 | |10;stanford-crypto-aes;73;82;73;72;77;72;71;72;73;72 17:29:07 INFO - PROCESS | 2464 | |11;stanford-crypto-ccm;131;127;120;132;117;116;114;120;158;127 17:29:07 INFO - PROCESS | 2464 | |12;stanford-crypto-pbkdf2;150;157;153;152;155;151;150;153;152;154 17:29:07 INFO - PROCESS | 2464 | |13;stanford-crypto-sha256-iterative;61;66;61;63;62;60;60;60;59;66 17:29:07 INFO - PROCESS | 2464 | __end_tp_report 17:29:07 INFO - PROCESS | 2464 | __start_cc_report 17:29:07 INFO - PROCESS | 2464 | _x_x_mozilla_cycle_collect,1689 17:29:07 INFO - PROCESS | 2464 | __end_cc_report 17:29:07 INFO - PROCESS | 2464 | __startTimestamp1475540947402__endTimestamp 17:29:07 INFO - PROCESS | 2464 | 17:29:07 INFO - PROCESS | 2464 | ------- Summary: start ------- 17:29:07 INFO - PROCESS | 2464 | Number of tests: 14 17:29:07 INFO - PROCESS | 2464 | 17:29:07 INFO - PROCESS | 2464 | [#0] ai-astar Cycles:10 Average:112.40 Median:111.50 stddev:3.17 (2.8%) stddev-sans-first:3.35 17:29:07 INFO - PROCESS | 2464 | Values: 113.0 113.0 121.0 111.0 111.0 111.0 110.0 111.0 111.0 112.0 17:29:07 INFO - PROCESS | 2464 | 17:29:07 INFO - PROCESS | 2464 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:146.00 stddev:22.94 (15.7%) stddev-sans-first:24.21 17:29:07 INFO - PROCESS | 2464 | Values: 147.0 118.0 152.0 178.0 116.0 145.0 140.0 117.0 173.0 120.0 17:29:07 INFO - PROCESS | 2464 | 17:29:07 INFO - PROCESS | 2464 | [#2] audio-dft Cycles:10 Average:161.90 Median:161.00 stddev:3.67 (2.3%) stddev-sans-first:3.87 17:29:07 INFO - PROCESS | 2464 | Values: 161.0 161.0 160.0 172.0 160.0 161.0 160.0 160.0 163.0 161.0 17:29:07 INFO - PROCESS | 2464 | 17:29:07 INFO - PROCESS | 2464 | [#3] audio-fft Cycles:10 Average:96.60 Median:75.00 stddev:45.32 (60.4%) stddev-sans-first:47.39 17:29:07 INFO - PROCESS | 2464 | Values: 75.0 75.0 75.0 178.0 75.0 75.0 76.0 75.0 187.0 75.0 17:29:07 INFO - PROCESS | 2464 | 17:29:07 INFO - PROCESS | 2464 | [#4] audio-oscillator Cycles:10 Average:105.40 Median:82.50 stddev:36.88 (44.7%) stddev-sans-first:38.29 17:29:07 INFO - PROCESS | 2464 | Values: 84.0 82.0 170.0 82.0 82.0 84.0 143.0 83.0 83.0 161.0 17:29:07 INFO - PROCESS | 2464 | 17:29:07 INFO - PROCESS | 2464 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 17:29:07 INFO - PROCESS | 2464 | Values: 117.0 117.0 116.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 17:29:07 INFO - PROCESS | 2464 | 17:29:07 INFO - PROCESS | 2464 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 17:29:07 INFO - PROCESS | 2464 | Values: 146.0 147.0 145.0 146.0 146.0 146.0 146.0 147.0 145.0 145.0 17:29:07 INFO - PROCESS | 2464 | 17:29:07 INFO - PROCESS | 2464 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 17:29:07 INFO - PROCESS | 2464 | Values: 94.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 17:29:07 INFO - PROCESS | 2464 | 17:29:07 INFO - PROCESS | 2464 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:50.00 stddev:2.50 (5.0%) stddev-sans-first:2.60 17:29:07 INFO - PROCESS | 2464 | Values: 49.0 50.0 49.0 50.0 51.0 57.0 51.0 50.0 49.0 48.0 17:29:07 INFO - PROCESS | 2464 | 17:29:07 INFO - PROCESS | 2464 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 17:29:07 INFO - PROCESS | 2464 | Values: 60.0 60.0 59.0 60.0 61.0 60.0 59.0 61.0 60.0 61.0 17:29:07 INFO - PROCESS | 2464 | 17:29:07 INFO - PROCESS | 2464 | [#10] stanford-crypto-aes Cycles:10 Average:73.70 Median:73.00 stddev:3.33 (4.6%) stddev-sans-first:3.53 17:29:07 INFO - PROCESS | 2464 | Values: 73.0 82.0 73.0 72.0 77.0 72.0 71.0 72.0 73.0 72.0 17:29:07 INFO - PROCESS | 2464 | 17:29:07 INFO - PROCESS | 2464 | [#11] stanford-crypto-ccm Cycles:10 Average:126.20 Median:127.00 stddev:12.84 (10.1%) stddev-sans-first:13.50 17:29:07 INFO - PROCESS | 2464 | Values: 131.0 127.0 120.0 132.0 117.0 116.0 114.0 120.0 158.0 127.0 17:29:07 INFO - PROCESS | 2464 | 17:29:07 INFO - PROCESS | 2464 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.70 Median:153.00 stddev:2.21 (1.4%) stddev-sans-first:2.12 17:29:07 INFO - PROCESS | 2464 | Values: 150.0 157.0 153.0 152.0 155.0 151.0 150.0 153.0 152.0 154.0 17:29:07 INFO - PROCESS | 2464 | 17:29:07 INFO - PROCESS | 2464 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:61.50 stddev:2.49 (4.0%) stddev-sans-first:2.62 17:29:07 INFO - PROCESS | 2464 | Values: 61.0 66.0 61.0 63.0 62.0 60.0 60.0 60.0 59.0 66.0 17:29:07 INFO - PROCESS | 2464 | -------- Summary: end -------- 17:29:07 INFO - PROCESS | 2464 | 17:29:08 INFO - TEST-INFO | 2464: exit 0 17:29:08 INFO - TEST-OK | kraken | took 60049ms 17:29:08 INFO - SUITE-END | took 1173s 17:29:08 INFO - Completed test suite (00:19:33) 17:29:08 INFO - javascript benchmark 17:29:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3227.5158726191075, 3239.8380080995953, 3247.090814571919, 3250.317433338999, 3281.73746100312, 5548.528375088117, 5631.57763167763, 5644.153376993453, 5665.320161580611, 5688.402717714637, 5681.517071048966, 5764.538836893049, 5767.913481297784, 5768.307803063638, 5772.134179873021, 5217.58259339252, 5230.607704422174, 5243.9213411798855, 5276.2085687146855, 5287.841364759044, 5085.084684756743, 5085.618578606601, 5314.867128321804, 5318.9202161967405, 5346.251524786533, 5091.338126043609, 5099.770510327045, 5108.055009823176, 5140.871478213037, 5152.768125434346, 5164.302819119408, 5198.532132108128, 5275.419703832576, 5287.524122828964, 5308.336457942757, 5040.823571175028, 5107.004134193834, 5187.403448603383, 5241.318628578299, 5253.684778913239, 5508.779648814043, 5520.751566179491, 5540.085885828834, 5544.77820887164, 5594.188842617838, 5953.166556682068, 6014.8195554133445, 6056.909146362808, 6066.302375226861, 6071.453569178795, 5877.559183061245, 6004.0, 6108.022716365404, 6111.388861113897, 6118.265808103011, 5564.554835613141, 5687.573431992617, 5713.600047996601, 5739.081746920473, 5742.856428589277, 6215.195800085661, 6233.812985610439, 6255.906161407583, 6270.2789179244055, 6270.623762574261, 5780.075187969988, 5872.823815285548, 6008.849778755566, 6018.15745795589, 6018.879622407528, 6083.855306140741, 6089.69551522431, 6111.35830737775, 6124.632522048602, 6131.386861313922, 6049.697864108646, 6050.062240352752, 6053.303870055, 6053.51571874249, 6096.0, 5844.1818145459665, 5899.439553242435, 6002.849928751729, 6054.243219597551, 6065.332813390523, 4588.564086411786, 4637.884052898705, 4640.65195110368, 4674.742889141062, 4706.364640773451, 4128.298189307825, 4154.273002224599, 4158.2307273154565, 4183.288840897055, 4183.97908010458, 5640.7743690252755, 5809.5352371811, 5830.912536311958, 5835.095560188178, 5835.533157347487, 5981.312149102821, 6023.728932197974, 6071.51427885768, 6120.020512922583, 6135.386461353918, 5791.362950075489, 5795.159701843293, 5796.188533605298, 5796.304443466811, 5799.449052340021, 5248.160294352885, 5249.317588713443, 5250.816221432232, 5257.947420525746, 5294.338207724035, 6062.393760623902, 6200.689965501707, 6230.59501132425, 6256.436920677161, 6388.936110638834, 6083.543734220039, 6153.384661533811, 6161.199044124414, 6162.106494558174, 6176.104464852481, 6377.394147555975, 6390.456811171102, 6393.584417012879, 6397.776077837253, 6405.263394709668, 5845.327787304344, 5885.823425297248, 5936.8812623748145, 5947.821565353046, 6053.3946605339115, 6112.083187521729, 6116.724747386467, 6122.4795892347865, 6123.663198524124, 6126.877462450815, 5928.140419639214, 5978.372270911492, 5980.5215582754145, 5982.790602328898, 5986.551008674454, 5800.767969281182, 5825.184474173534, 5858.882822343615, 5881.911771323519, 5982.4017598239825, 5861.35525092248, 5863.296404431496, 5866.149408335853, 5866.266716660418, 5880.794172203952, 5908.409159084057, 5933.554983376351, 5965.313988891333, 5986.042233242661, 5993.310769261417, 5696.629719068247, 5731.312242530923, 5753.71231438418, 5784.971075144766, 5804.912926306024], "value": 5600.570225267064, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.0524862843763, 638.9776357827476, 670.7887015590087, 678.1082876018036, 692.2039654397439, 35330.64669353309, 46184.76907615461, 46947.53052469475, 46971.0, 47069.76465117678, 49655.75172124143, 49856.50143498564, 49994.0, 50107.49892501074, 50123.749381253045, 8485.448445851, 9312.487813170274, 9445.197158241557, 9460.526973651324, 9462.668806591771, 10817.783644327108, 11151.330920144777, 11177.72055698606, 11530.769384612302, 11536.077113830874, 11410.144239182095, 11525.36610486423, 11539.0, 11542.07663386927, 11547.018503427218, 11188.888111118866, 11233.494492747846, 11239.044681202107, 11240.550377984871, 11244.662660120128, 11029.338239705643, 11099.0, 11104.722381940515, 11104.722381940515, 11105.278156919774, 45008.099838003385, 45442.31836522455, 45492.090158196654, 45495.772521137515, 45565.0, 3981.94027089591, 4088.8160032798596, 4120.484939382577, 4271.508776490712, 4349.478062632473, 34041.14897127567, 34948.47577286343, 35068.0, 35375.0, 35954.64045359539, 4605.217113090783, 4674.859754207379, 4697.084068606623, 4702.6002789762915, 4788.904221915543, 380172.0, 380503.0, 382234.0, 382636.0, 383044.0, 38318.0, 62641.37358626447, 62698.0, 62915.0, 63078.0, 40648.79675601662, 40705.38941915874, 40890.79554602208, 40909.0, 40962.59037409647, 17087.914560427365, 17208.569785755204, 17208.569785755455, 17211.655766884593, 17216.0, 12116.636500904988, 12987.41556629935, 13284.601461956157, 15066.472673456376, 15154.545363638888, 9259.212966897754, 9264.629414823467, 9271.629134834531, 9326.067393260755, 9362.0, 3501.509788629594, 3583.8387272572795, 3607.0322645090714, 3621.6197299283194, 3640.8543658253902, 948.4925564822787, 1043.7755882485303, 1056.9559865334622, 1060.6499855047816, 1115.263925808962, 35052.0, 35122.12194695102, 35130.64869351325, 35157.12107197289, 35330.64669353273, 1139.44817300522, 1157.3403160198775, 1181.0, 1203.4283715235192, 1213.8422005139275, 18101.818981810276, 18157.273709051755, 18303.35938242155, 18333.449996500127, 18364.0, 608363.0, 612492.0, 617421.0, 617434.0, 622257.0, 63137.684311578145, 63466.68266658637, 63556.0, 63563.68218158972, 63780.68109659515, 1282.4677758730022, 1386.2652794019186, 1413.22978976456, 1413.2297897645808, 1445.3712635003596, 33962.49056264207, 35391.646083538835, 35566.64433355633, 35594.82202589074, 35793.0, 54095.0, 54731.0, 54738.72630636821, 54778.72610636921, 54818.451815481334, 414598.0, 416576.0, 417205.0, 418053.0, 418419.0, 27187.86406067957, 31221.37557248797, 31345.373092537564, 31708.682913171495, 32793.34413311673, 51573.48426515687, 51647.22529161991, 52222.73888630533, 52473.0, 52515.21227181519, 43535.4483635595, 44194.558054419045, 44452.777736111115, 45749.31376029296, 46260.7686961563, 43553.346699798894, 43880.0, 45238.77380613076, 45844.54155458403, 45912.08175836531, 44046.11907761763, 44889.0, 44965.325520117876, 46860.7656961713, 47271.29093063538, 39119.80440097781, 40758.79620601878, 41035.58964410437, 41161.38257926074, 41206.793966029974, 30412.239694007825, 30641.40378943087, 30774.38451230918, 30935.226619334695, 30951.0], "value": 20846.42220894443, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.46179083459447, 430.37196434060877, 433.16615515133367, 435.2752666809763, 442.73214705103413, 18519.81480185198, 19862.801371986276, 20346.491337716565, 20719.171233150657, 20899.68650470244, 19263.940483273414, 19403.4178974631, 19669.114889829958, 19863.900680496612, 19984.20063197474, 5912.4974377178, 6142.508599312045, 6143.385661433866, 6146.38536146384, 6146.569740118193, 5991.071383935475, 6018.638881667114, 6057.273127224739, 6078.6048906820915, 6097.5426842986735, 6013.646929440863, 6015.127806468059, 6017.217761690974, 6020.096985452174, 6020.96989515054, 5958.702064896759, 6028.5478589106, 6080.787172448987, 6081.969590152065, 6083.999999999978, 5284.0, 5296.947030529684, 5415.783368665248, 5426.9185962210595, 5427.294451721291, 13886.583402497941, 14823.77764333536, 14844.628884277872, 14855.108693478425, 21022.789772102235, 2332.183735692494, 2468.962965555518, 3037.179961410407, 3138.6547479777205, 3153.495440729472, 14334.283285835667, 14509.709805803825, 14592.562223133324, 14897.404103835834, 15326.15706136162, 1926.3450426854797, 1957.5106223444138, 1958.8237058664647, 1959.1183967214668, 1964.8329891959202, 60333.69833150806, 77727.61136194339, 78491.0, 82059.0, 86980.13019869784, 11405.201635885409, 11463.197576169754, 11544.307341559534, 11692.181547291779, 11988.220765650205, 16364.018158910503, 16524.339026439047, 16710.749338759928, 16777.245023973952, 16786.664266714597, 8586.270167035747, 8921.509316987627, 8959.283257339397, 9045.954770226106, 9217.76955576116, 8749.212570868653, 8798.38411311202, 8800.295976321879, 8981.685641002534, 9003.684871029614, 5002.324686167321, 5049.899002019939, 5092.694438333712, 5119.8720031998755, 5181.145111056709, 1990.5919286546514, 2062.3086871632663, 2076.8234700050666, 2098.655782530148, 2101.3065688322818, 991.4398364923795, 1013.9188864890791, 1018.3075508654039, 1025.4462590201356, 1046.2780681329857, 19660.0, 20533.2813351532, 20544.897275513827, 20581.691274630593, 20793.854544909624, 996.0437979539578, 1032.0762917189224, 1070.796548655753, 1088.357868857378, 1107.3300653104916, 11042.889571104346, 11390.715232119263, 11437.142214333791, 11637.59268425618, 11687.532498700128, 499559.0, 502712.0, 508223.0, 524763.0, 527675.0, 91924.0, 93719.53140234255, 94606.0, 98032.0, 100905.0, 985.2216748768393, 1009.2228983682523, 1016.5476363018386, 1029.711680729397, 1031.63377001165, 17260.654786904444, 17939.910300448413, 18116.63766724632, 18369.816301837345, 18497.53756156107, 41629.0, 42470.5752942479, 42569.23299985491, 42637.786811065744, 43226.0, 55867.72066139643, 59988.10017849648, 60526.394736052076, 61226.0, 61467.69266153641, 8001.799955001172, 8419.284360829153, 8731.738047858575, 8946.0, 9130.04134565861, 12727.363631818187, 13223.735525289248, 13713.588592341846, 13783.104098233583, 14065.929670351583, 11647.0, 11869.347185904859, 12211.328376939353, 12325.260484370967, 12382.195157314745, 10460.424676642857, 11577.305361677989, 11867.643970680894, 12161.270323780602, 12226.388680565758, 11504.36725980079, 11984.247561170861, 12248.326342051272, 12529.624111276677, 12706.745865082832, 8817.338699597427, 9056.366054376394, 9079.092090790868, 9201.539923003957, 9457.0, 9167.609374922113, 11769.588064417705, 11800.76398472043, 12115.878841211828, 12453.0], "value": 9611.54545457433, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1431.8997670163092, 1538.8384219656932, 1549.1015211177519, 1719.9484015479532, 1841.8894866308021, 8993.145651163137, 9407.764805879848, 9449.574769135388, 9729.124378805906, 9746.756331091727, 9557.856632150506, 9658.275629327793, 9680.080392362714, 9682.903170968288, 9688.806223875536, 2451.6690246816675, 2493.613489909067, 2494.052260141153, 2494.3514686181543, 2498.812589055819, 2466.2847774145475, 2538.2385284414695, 2543.135333986444, 2545.2491515003026, 2548.7068987066446, 2486.751324867517, 2495.8502489850575, 2544.2875994721417, 2549.1970029440668, 2550.1966880432697, 2431.234161239204, 2452.730199678034, 2461.692288463942, 2462.4336402627496, 2463.790577800889, 2428.453597940467, 2449.326435230308, 2457.017193122765, 2459.8524088554746, 2476.4675594747214, 9587.089226523469, 9644.469554174517, 9675.612975480972, 9738.123568878835, 9755.951220243924, 5476.205950137248, 5728.856778580527, 5738.7991420300505, 5739.885202295973, 5743.16724075011, 3714.7771133732062, 3788.829502672359, 3832.6550610445192, 3840.519935008124, 3856.2865869814173, 1182.4442512019423, 1183.5206741269799, 1189.6550000499844, 1217.8903898649075, 1238.7150955280247, 13740.312984350743, 13821.378037988316, 13878.236696981661, 13886.09740366883, 13909.58271251864, 4757.714537127714, 4811.109944660249, 4832.613390928717, 4889.779959901813, 4969.3291405659775, 10768.192385571114, 10800.675979720621, 10804.67585972422, 10804.729881753019, 10805.081568066646, 5560.944390556043, 5561.972190139023, 5562.221289019541, 5564.33228012641, 5565.888682226333, 4663.463701674283, 4703.412073490814, 5152.381714194245, 5201.635885487979, 5207.9844430335725, 4175.91648167035, 4178.310178201377, 4224.978875105605, 4255.404243405926, 4264.530901600821, 1181.3502573584494, 1195.970100747471, 1197.26368283505, 1206.324458303353, 1221.407617305606, 1029.7888932768765, 1043.2853495355705, 1050.2858056521486, 1051.7738686182354, 1057.2282233969245, 6173.506119510428, 6223.0, 6400.455961243347, 6538.8365290868105, 6790.660466976631, 485.2599785327404, 489.3858207949001, 492.308306828906, 495.1285737102725, 496.2407516499761, 4260.573942605776, 4318.438602981655, 4326.459192600925, 4326.6754993375625, 4372.737635741865, 11958.342291174069, 12395.690107747378, 12407.875921240671, 12414.689632759253, 12432.689182770322, 12317.07621928359, 12352.135350525377, 12383.318917459446, 12389.256644601353, 12429.254244745343, 8626.223639872442, 9010.819783604291, 9042.321825862955, 9139.086091390807, 9242.168204861595, 6534.738610455529, 6605.240397354366, 6624.966875165594, 6819.318068193141, 6894.345037221657, 10929.508172132115, 11541.711457213636, 11562.537498500302, 11626.941865290957, 11632.767344653228, 10314.484275786332, 10854.511546980248, 11459.312441253218, 11680.649580512598, 11893.286402815858, 8380.497170169809, 8711.47731136134, 8749.693760718344, 8827.602757875782, 8902.688405905763, 9495.335326527076, 9642.276829237695, 9646.614135434504, 9658.275629327689, 9660.420374777535, 8534.914650853412, 8593.65625374978, 8684.478931263891, 8715.694950676696, 8721.258693011165, 8375.58122093905, 8376.790580235544, 8397.0, 8461.365397595082, 8471.0, 7732.30409263169, 7854.096778870503, 7916.881246781188, 7921.128675845767, 7952.403569732178, 5423.701696406735, 5489.917651235155, 5575.386707462257, 5578.888422231452, 5631.7184140793615, 5664.971675141598, 5666.376698563237, 5673.602847800615, 5700.914486282626, 5805.506531944832], "value": 4939.014456282012, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.73721077797035, 352.96646818552244, 354.3108653668614, 365.8024666679991, 366.55646667532284, 5524.530414914732, 5540.534462164338, 5550.862073274979, 5552.639078459897, 5572.219889215513, 5462.562994960404, 5472.522418946882, 5491.258680078188, 5519.282493275879, 5538.224648549206, 4250.171216612754, 4406.272964960794, 4418.923106140109, 4426.380306757057, 4443.88890277744, 4367.323064924942, 4373.868783936487, 4378.6934914555995, 4383.9342409863875, 4409.338599210112, 4166.708330416872, 4359.149965756678, 4371.803268852894, 4401.207782599147, 4412.735235885857, 4141.958580414187, 4342.5874541918465, 4351.6518678505645, 4376.6936314458, 4396.648268138573, 3910.3156947534067, 3921.450996860442, 3921.941170882439, 3924.666403355718, 3927.4501569780255, 5236.6857988520815, 5421.322334708161, 5443.129099344125, 5456.781728730846, 5458.85364073545, 4943.6539442239055, 4947.158982972904, 4966.2798894160505, 4988.127077761414, 5003.774830132653, 5695.572832037614, 5721.513671337942, 5730.34101078373, 5754.712264386764, 5779.219805326311, 4092.386142078712, 4252.702310838243, 4257.425247591566, 4268.0823622921225, 4287.892802679958, 5954.910676339815, 5959.940400596025, 5968.7015649217365, 5977.7608895644125, 5980.491658209101, 4409.492908315585, 4413.6248418884115, 4415.933760993587, 4430.892276930767, 4451.710638808467, 4420.270655341833, 4450.532694067077, 4524.615407690318, 4525.456945166601, 4537.0925814837165, 4974.9303899661745, 4984.675996060245, 4992.700437973734, 4994.625403094782, 4997.275395067695, 5743.971280143656, 5781.19063331134, 5788.623739456922, 5789.350035239936, 5817.621854579439, 1473.8157730283715, 1539.7921280626972, 1549.9535013949599, 1550.6511035017031, 1557.6728886933645, 998.0618218874235, 1007.3603261928728, 1015.2284263959297, 1031.5976769060073, 1040.625374865048, 4921.252955200852, 4947.293183851565, 4967.379077615298, 4970.726610036482, 4971.45314015458, 5147.485251474823, 5161.458046905096, 5169.508896654812, 5187.58499320061, 5198.570393141917, 4983.352164218701, 5394.649347792381, 5488.313960754906, 5560.805371811967, 5632.53554075935, 3083.8766449342224, 3119.656837747846, 3124.656287808339, 3138.905832825019, 3160.541721450377, 5899.557533185029, 6090.23872015998, 6105.175801266861, 6113.174721412553, 6152.261728592597, 6064.3935606439, 6064.423879731418, 6070.994857545543, 6078.726457309432, 6108.908366374508, 6189.700162965828, 6190.845228869134, 6198.256209254918, 6238.500919926395, 6251.562390632703, 5713.600047996767, 5894.410558944071, 5932.080527518242, 5964.642121472725, 5969.403059693996, 5898.64608123514, 5907.81843631284, 5911.349751527415, 5945.375735547878, 5960.254968128984, 5936.970315148396, 6167.84580385494, 6176.227971503562, 6215.595986260879, 6244.2819075806865, 4291.549387314287, 4308.332208507686, 4326.351047342924, 4358.433403657505, 4360.514506731133, 5975.820725378246, 5978.850528736816, 6022.789202377896, 6063.696815159136, 6067.575269731076, 5719.942800571941, 5786.276715410574, 5810.854728631818, 5819.912701309399, 5823.8835223294445, 5766.654000759968, 5804.593678442468, 5810.73851676684, 5812.94187058141, 5902.173695682522, 5575.804846830341, 5784.843031393654, 5824.9708751457665, 5864.765409383577, 5873.2658417697785, 5845.503132233808, 5874.324452687824, 5874.94125058744, 5880.765309182654, 5887.146363777314, 5748.425157484218, 5761.222234998221, 5761.596688231783, 5847.473727364558, 5856.736446859816], "value": 4438.5486303494845, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [817.7955511122219, 848.561687938943, 861.7802460372604, 875.7766769473783, 928.3130483442251, 15956.0, 17517.299308027694, 17552.385666501676, 17569.385071522498, 17643.11784410779, 16206.513804585882, 16413.343466261336, 16418.589535261624, 16491.09298988555, 16496.587585310343, 718.1669263654162, 834.4284165346727, 835.1940377535677, 844.1642773654099, 848.6053984897021, 862.180928118287, 870.8954925408959, 871.0766587417357, 871.960761765722, 872.5549969515529, 926.5043201886991, 928.1924725488834, 928.2898582584328, 928.7306681062483, 929.0152438415302, 917.3807679816131, 924.1405492891608, 925.4169872980046, 925.7593025813337, 926.0878035135386, 673.293042305582, 676.8375589858399, 677.5900580148988, 678.1726293921408, 678.4504551313403, 16339.18304084803, 16459.753103703453, 16476.0, 16509.66980660392, 16535.83464165355, 16112.838871611368, 16829.0, 16856.747148792656, 17177.141142942804, 17685.734713979302, 13319.334033298297, 13673.314518968931, 13719.314034298246, 13779.448822047107, 13929.164250144922, 4051.351783714621, 4077.4495443115393, 4089.161721847014, 4105.44576482174, 4225.894352641178, 68351.316486835, 70716.64641676862, 84443.0, 84610.57694711487, 85667.57166214129, 12320.507179712891, 12699.809502857464, 12735.299558524184, 12735.299558524184, 12738.426770795151, 22090.33728988133, 22280.442988925406, 22377.216797412013, 22482.66276005861, 22504.43738906508, 9217.44695318283, 9353.204977576846, 9484.573194206278, 9506.714798556055, 9709.611615535441, 4858.73276969763, 5023.472535383805, 5107.412647545506, 5162.845114646567, 5206.140986737221, 3796.449514820391, 3912.960870391317, 3945.349017412152, 3945.6646185074014, 3946.2304850553865, 682.222266616058, 683.6991723641581, 703.63762662229, 714.8963400306926, 718.0306586108816, 588.1207594646031, 628.9433950944436, 690.3683129936153, 714.078838298589, 745.2137994415835, 10060.74848128788, 10099.747506312255, 10536.15710743154, 10621.575136994588, 10881.510332035077, 705.2594775485719, 712.5855177472713, 729.9708011679579, 754.3964828137468, 781.1407451803085, 4985.9115525268935, 5032.47159048302, 5100.770465329069, 5142.562882155059, 5161.225816127611, 73565.26434735692, 74232.62883685654, 74232.62883685654, 101541.0, 111856.0, 118215.0, 122059.38970305269, 122375.0, 122758.99999999821, 123541.0, 99821.50089249652, 104875.47562262292, 105310.47344763228, 105344.99999999847, 106999.0, 9494.90505094968, 9516.38143520669, 9746.951265243628, 10082.546285417027, 10102.494875256061, 63065.684671576346, 63599.0, 64312.67843660939, 64353.678231608545, 65899.34100659123, 89420.32365800183, 95066.52466737622, 95300.0, 96407.0, 98252.0, 12565.246085234916, 12670.556530521388, 12737.617871463872, 12801.359932003548, 12987.35063246815, 13630.727385452037, 13655.248961306826, 14345.928270358581, 14489.927550362181, 14836.554903352917, 13563.525276615272, 13698.38357273905, 14481.710365792836, 14982.325795339018, 15041.323140458924, 12081.69795755078, 12672.429740661508, 12720.554780582635, 12976.545820896223, 13518.391672374963, 12893.613191604267, 12935.870641293843, 13017.544385946447, 13719.245441500812, 13785.724285514434, 9779.951100244738, 10230.795384092426, 10458.111060559937, 10530.736731581464, 11047.502862371373, 11227.270227435518, 11254.155938304497, 11378.37418941933, 11402.48688808989, 11441.199116061796], "value": 8059.279200443352, "unit": "score"}], "name": "dromaeo_css", "value": 7636.237202040709, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 121.0, 111.0, 111.0, 111.0, 110.0, 111.0, 111.0, 112.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 118.0, 152.0, 178.0, 116.0, 145.0, 140.0, 117.0, 173.0, 120.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 172.0, 160.0, 161.0, 160.0, 160.0, 163.0, 161.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 178.0, 75.0, 75.0, 76.0, 75.0, 187.0, 75.0], "value": 96.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 170.0, 82.0, 82.0, 84.0, 143.0, 83.0, 83.0, 161.0], "value": 105.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 145.0, 146.0, 146.0, 146.0, 146.0, 147.0, 145.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 49.0, 50.0, 51.0, 57.0, 51.0, 50.0, 49.0, 48.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 60.0, 61.0, 60.0, 59.0, 61.0, 60.0, 61.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 82.0, 73.0, 72.0, 77.0, 72.0, 71.0, 72.0, 73.0, 72.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 127.0, 120.0, 132.0, 117.0, 116.0, 114.0, 120.0, 158.0, 127.0], "value": 126.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 157.0, 153.0, 152.0, 155.0, 151.0, 150.0, 153.0, 152.0, 154.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 66.0, 61.0, 63.0, 62.0, 60.0, 60.0, 60.0, 59.0, 66.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1499.2, "alertThreshold": 2.0}]} 17:29:08 INFO - Return code: 0 17:29:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:29:08 INFO - # TBPL SUCCESS # 17:29:08 INFO - Running post-action listener: _resource_record_post_action 17:29:08 INFO - [mozharness: 2016-10-04 00:29:08.689790Z] Finished run-tests step (success) 17:29:08 INFO - Running post-run listener: _resource_record_post_run 17:29:08 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289382400; Read time: 12; Write time: 235484 17:29:08 INFO - TinderboxPrint: CPU usage
12.9% 17:29:08 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 17:29:08 INFO - TinderboxPrint: I/O write bytes / time
289,382,400 / 235,484 17:29:08 INFO - TinderboxPrint: CPU idle
8,259.3 (87.1%) 17:29:08 INFO - TinderboxPrint: CPU user
1,181.2 (12.5%) 17:29:08 INFO - TinderboxPrint: Swap in / out
0 / 0 17:29:08 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70074368; Read time: 0; Write time: 89736 17:29:08 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 219308032; Read time: 12; Write time: 145748 17:29:09 INFO - Running post-run listener: _upload_blobber_files 17:29:09 INFO - Blob upload gear active. 17:29:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:29:09 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 17:29:09 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'] 17:29:09 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 17:29:09 INFO - (blobuploader) - INFO - Open directory for files ... 17:29:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:29:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:29:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:29:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:29:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:29:12 INFO - (blobuploader) - INFO - Done attempting. 17:29:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:29:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:29:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:29:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:29:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:29:13 INFO - (blobuploader) - INFO - Done attempting. 17:29:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:29:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:29:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:29:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:29:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:29:14 INFO - (blobuploader) - INFO - Done attempting. 17:29:14 INFO - (blobuploader) - INFO - Iteration through files over. 17:29:14 INFO - Return code: 0 17:29:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:29:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:29:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7da7147a2b72a430be0a05605cc83f1c8eb706a47bf347442475bfb9de5a0ce8f5300eab74407d725dfa4ca6b8889efc3a7ebd3c903af81bbdae046b934eaafe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/24203c3c4d7356593607d38fa3ac87f78cc4b95f67a761480361a21db2bac7bc753df70c31b6603ca6d887ed96fbe88384119a808d140ad7907ea6f23233d79f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e9bc609d7dddf54143c4625521c930e5aba026cc7d974041e40f22ab19bd7c23a712ef3e8da58afd192d3eb27126682b49299ff6082aaaf6c45c8935246b6cc6"} 17:29:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:29:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:29:14 INFO - Contents: 17:29:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7da7147a2b72a430be0a05605cc83f1c8eb706a47bf347442475bfb9de5a0ce8f5300eab74407d725dfa4ca6b8889efc3a7ebd3c903af81bbdae046b934eaafe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/24203c3c4d7356593607d38fa3ac87f78cc4b95f67a761480361a21db2bac7bc753df70c31b6603ca6d887ed96fbe88384119a808d140ad7907ea6f23233d79f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e9bc609d7dddf54143c4625521c930e5aba026cc7d974041e40f22ab19bd7c23a712ef3e8da58afd192d3eb27126682b49299ff6082aaaf6c45c8935246b6cc6"} 17:29:14 INFO - Running post-run listener: copy_logs_to_upload_dir 17:29:14 INFO - Copying logs to upload dir... 17:29:14 INFO - mkdir: /builds/slave/test/build/upload/logs 17:29:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1675.022368 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-03 17:29:13.889592) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 17:29:13.897146) ========= 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=41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607 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/7da7147a2b72a430be0a05605cc83f1c8eb706a47bf347442475bfb9de5a0ce8f5300eab74407d725dfa4ca6b8889efc3a7ebd3c903af81bbdae046b934eaafe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/24203c3c4d7356593607d38fa3ac87f78cc4b95f67a761480361a21db2bac7bc753df70c31b6603ca6d887ed96fbe88384119a808d140ad7907ea6f23233d79f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e9bc609d7dddf54143c4625521c930e5aba026cc7d974041e40f22ab19bd7c23a712ef3e8da58afd192d3eb27126682b49299ff6082aaaf6c45c8935246b6cc6"} build_url:https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011379 build_url: 'https://queue.taskcluster.net/v1/task/MakJ_basQu2JjG-Tjg84HQ/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/7da7147a2b72a430be0a05605cc83f1c8eb706a47bf347442475bfb9de5a0ce8f5300eab74407d725dfa4ca6b8889efc3a7ebd3c903af81bbdae046b934eaafe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/24203c3c4d7356593607d38fa3ac87f78cc4b95f67a761480361a21db2bac7bc753df70c31b6603ca6d887ed96fbe88384119a808d140ad7907ea6f23233d79f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e9bc609d7dddf54143c4625521c930e5aba026cc7d974041e40f22ab19bd7c23a712ef3e8da58afd192d3eb27126682b49299ff6082aaaf6c45c8935246b6cc6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 17:29:13.930850) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 17:29:13.931274) ========= 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=41a7e32711b8e08598a63237000001d6-1475536873.856288-512363607 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004474 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 17:29:14.009787) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-03 17:29:14.010255) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-03 17:29:14.010568) ========= ========= Total master_lag: 0.20 =========