builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-053 starttime: 1476205844.42 results: success (0) buildid: 20161011072046 builduid: c1f6e99f50c94cc8bc8c0737b308404c revision: d1b244e09392f8d17ae23403662dedcbce10eea9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-11 10:10:44.415846) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-11 10:10:44.416320) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-11 10:10:44.416589) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-11 10:10:44.457149) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 10:10:44.457405) ========= 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=920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-11 10:10:44-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 26.9M=0s 2016-10-11 10:10:44 (26.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.178201 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 10:10:44.653529) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 10:10:44.653840) ========= 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=920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013770 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 10:10:44.703191) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 10:10:44.703523) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d1b244e09392f8d17ae23403662dedcbce10eea9 --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 d1b244e09392f8d17ae23403662dedcbce10eea9 --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=920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-11 10:10:44,758 truncating revision to first 12 chars 2016-10-11 10:10:44,758 Setting DEBUG logging. 2016-10-11 10:10:44,758 attempt 1/10 2016-10-11 10:10:44,758 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d1b244e09392?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-11 10:10:45,229 unpacking tar archive at: mozilla-beta-d1b244e09392/testing/mozharness/ program finished with exit code 0 elapsedTime=0.814174 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 10:10:45.530201) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-11 10:10:45.530513) ========= script_repo_revision: d1b244e09392f8d17ae23403662dedcbce10eea9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-11 10:10:45.530900) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-11 10:10:45.531200) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-11 10:10:45.538571) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-10-11 10:10:45.538846) ========= /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=920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:10:45 INFO - MultiFileLogger online at 20161011 10:10:45 in /builds/slave/test 10:10:45 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 10:10:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:10:45 INFO - {'append_to_log': False, 10:10:45 INFO - 'base_work_dir': '/builds/slave/test', 10:10:45 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:10:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:10:45 INFO - 'branch': 'Mozilla-Beta', 10:10:45 INFO - 'buildbot_json_path': 'buildprops.json', 10:10:45 INFO - 'config_files': ('talos/linux_config.py',), 10:10:45 INFO - 'default_actions': ('clobber', 10:10:45 INFO - 'read-buildbot-config', 10:10:45 INFO - 'download-and-extract', 10:10:45 INFO - 'populate-webroot', 10:10:45 INFO - 'create-virtualenv', 10:10:45 INFO - 'install', 10:10:45 INFO - 'run-tests'), 10:10:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:10:45 INFO - 'download_minidump_stackwalk': True, 10:10:45 INFO - 'download_symbols': 'ondemand', 10:10:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:10:45 INFO - 'tooltool.py': '/tools/tooltool.py', 10:10:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:10:45 INFO - '/tools/misc-python/virtualenv.py')}, 10:10:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:10:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:10:45 INFO - 'installer_path': 'installer.exe', 10:10:45 INFO - 'log_level': 'info', 10:10:45 INFO - 'log_name': 'talos', 10:10:45 INFO - 'log_to_console': True, 10:10:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:10:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:10:45 INFO - 'opt_config_files': (), 10:10:45 INFO - 'pip_index': False, 10:10:45 INFO - 'sps_profile': False, 10:10:45 INFO - 'sps_profile_interval': 0, 10:10:45 INFO - 'suite': 'dromaeojs', 10:10:45 INFO - 'system_bits': '32', 10:10:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:10:45 INFO - 'title': 'talos-linux64-ix-053', 10:10:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:10:45 INFO - 'use_talos_json': True, 10:10:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:10:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:10:45 INFO - 'work_dir': 'build'} 10:10:45 INFO - [mozharness: 2016-10-11 17:10:45.780567Z] Running clobber step. 10:10:45 INFO - Running pre-action listener: _resource_record_pre_action 10:10:45 INFO - Running main action method: clobber 10:10:45 INFO - rmtree: /builds/slave/test/build 10:10:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:10:46 INFO - Running post-action listener: _resource_record_post_action 10:10:46 INFO - [mozharness: 2016-10-11 17:10:46.197947Z] Finished clobber step (success) 10:10:46 INFO - [mozharness: 2016-10-11 17:10:46.198047Z] Running read-buildbot-config step. 10:10:46 INFO - Running pre-action listener: _resource_record_pre_action 10:10:46 INFO - Running main action method: read_buildbot_config 10:10:46 INFO - Using buildbot properties: 10:10:46 INFO - { 10:10:46 INFO - "project": "", 10:10:46 INFO - "product": "firefox", 10:10:46 INFO - "script_repo_revision": "production", 10:10:46 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 10:10:46 INFO - "repository": "", 10:10:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 10:10:46 INFO - "buildid": "20161011072046", 10:10:46 INFO - "pgo_build": "True", 10:10:46 INFO - "basedir": "/builds/slave/test", 10:10:46 INFO - "buildnumber": 411, 10:10:46 INFO - "slavename": "talos-linux64-ix-053", 10:10:46 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 10:10:46 INFO - "platform": "ubuntu64_hw", 10:10:46 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 10:10:46 INFO - "branch": "mozilla-beta", 10:10:46 INFO - "repo_path": "releases/mozilla-beta", 10:10:46 INFO - "revision": "d1b244e09392f8d17ae23403662dedcbce10eea9", 10:10:46 INFO - "stage_platform": "linux64-pgo", 10:10:46 INFO - "builduid": "c1f6e99f50c94cc8bc8c0737b308404c", 10:10:46 INFO - "slavebuilddir": "test" 10:10:46 INFO - } 10:10:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 10:10:46 INFO - Running post-action listener: _resource_record_post_action 10:10:46 INFO - [mozharness: 2016-10-11 17:10:46.200506Z] Finished read-buildbot-config step (success) 10:10:46 INFO - [mozharness: 2016-10-11 17:10:46.200596Z] Running download-and-extract step. 10:10:46 INFO - Running pre-action listener: _resource_record_pre_action 10:10:46 INFO - Running main action method: download_and_extract 10:10:46 INFO - mkdir: /builds/slave/test/build/tests 10:10:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:10:46 INFO - https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:10:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:10:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:10:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:10:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:10:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:10:46 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:10:46 INFO - retry: Failed, sleeping 30 seconds before retrying 10:11:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:11:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:11:16 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:11:16 INFO - retry: Failed, sleeping 60 seconds before retrying 10:12:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:12:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:12:16 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:12:16 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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! 10:12:16 INFO - Caught exception: HTTP Error 404: Not Found 10:12:16 INFO - Caught exception: HTTP Error 404: Not Found 10:12:16 INFO - Caught exception: HTTP Error 404: Not Found 10:12:16 INFO - trying https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:12:16 INFO - Downloading https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:12:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:12:16 INFO - Downloaded 1327 bytes. 10:12:16 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:12:16 INFO - Using the following test package requirements: 10:12:16 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 10:12:16 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:12:16 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 10:12:16 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:12:16 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 10:12:16 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:12:16 INFO - u'jsshell-linux-x86_64.zip'], 10:12:16 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:12:16 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 10:12:16 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 10:12:16 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:12:16 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 10:12:16 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:12:16 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 10:12:16 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:12:16 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 10:12:16 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:12:16 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 10:12:16 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:12: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')]} 10:12:16 INFO - https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:12:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:12:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:12:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:12:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:12:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:12:16 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:12:16 INFO - retry: Failed, sleeping 30 seconds before retrying 10:12:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:12:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:12:46 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:12:46 INFO - retry: Failed, sleeping 60 seconds before retrying 10:13:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:13:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:13:47 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:13:47 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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! 10:13:47 INFO - Caught exception: HTTP Error 404: Not Found 10:13:47 INFO - Caught exception: HTTP Error 404: Not Found 10:13:47 INFO - Caught exception: HTTP Error 404: Not Found 10:13:47 INFO - trying https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:13:47 INFO - Downloading https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:13:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:13:48 INFO - Downloaded 21773873 bytes. 10:13:48 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'] 10:13:48 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 10:13:50 INFO - Return code: 0 10:13:50 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 10:13:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:50 INFO - https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:13:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:13:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:13:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:13:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:13:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:13:50 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:13:50 INFO - retry: Failed, sleeping 30 seconds before retrying 10:14:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:14:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:14:20 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:14:20 INFO - retry: Failed, sleeping 60 seconds before retrying 10:15:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:15:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:15:20 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:15:20 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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! 10:15:20 INFO - Caught exception: HTTP Error 404: Not Found 10:15:20 INFO - Caught exception: HTTP Error 404: Not Found 10:15:20 INFO - Caught exception: HTTP Error 404: Not Found 10:15:20 INFO - trying https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:15:20 INFO - Downloading https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:15:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:15:21 INFO - Downloaded 21773873 bytes. 10:15:21 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'] 10:15:21 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 10:15:22 INFO - Return code: 0 10:15:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:22 INFO - https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:15:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:15:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:15:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:15:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:15:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:15:22 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:15:22 INFO - retry: Failed, sleeping 30 seconds before retrying 10:15:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:15:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:15:52 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:15:52 INFO - retry: Failed, sleeping 60 seconds before retrying 10:16:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:16:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:16:52 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:16:52 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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! 10:16:52 INFO - Caught exception: HTTP Error 404: Not Found 10:16:52 INFO - Caught exception: HTTP Error 404: Not Found 10:16:52 INFO - Caught exception: HTTP Error 404: Not Found 10:16:52 INFO - trying https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:16:52 INFO - Downloading https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:16:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:16:53 INFO - Downloaded 14413740 bytes. 10:16:53 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'] 10:16:53 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 10:16:54 INFO - Return code: 0 10:16:54 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')]} 10:16:54 INFO - https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:16:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:16:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:16:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:16:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:16:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:16:54 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:16:54 INFO - retry: Failed, sleeping 30 seconds before retrying 10:17:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:17:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:17:24 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:17:24 INFO - retry: Failed, sleeping 60 seconds before retrying 10:18:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:18:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:18:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:18:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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! 10:18:24 INFO - Caught exception: HTTP Error 404: Not Found 10:18:24 INFO - Caught exception: HTTP Error 404: Not Found 10:18:24 INFO - Caught exception: HTTP Error 404: Not Found 10:18:24 INFO - trying https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:18:24 INFO - Downloading https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:18:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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 10:18:25 INFO - Downloaded 56798976 bytes. 10:18:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:18:25 INFO - mkdir: /builds/slave/test/properties 10:18:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:18:25 INFO - Writing to file /builds/slave/test/properties/build_url 10:18:25 INFO - Contents: 10:18:25 INFO - build_url:https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:18:26 INFO - Running post-action listener: _resource_record_post_action 10:18:26 INFO - Running post-action listener: set_extra_try_arguments 10:18:26 INFO - [mozharness: 2016-10-11 17:18:26.426800Z] Finished download-and-extract step (success) 10:18:26 INFO - [mozharness: 2016-10-11 17:18:26.427033Z] Running populate-webroot step. 10:18:26 INFO - Running pre-action listener: _resource_record_pre_action 10:18:26 INFO - Running main action method: populate_webroot 10:18:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:18:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:18:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:18:26 INFO - '--tppagecycles', 10:18:26 INFO - '7'], 10:18:26 INFO - 'tests': ['tsvgm']}}, 10:18:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:18:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:18:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:18:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:18:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:18:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:18:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:26 INFO - 'talos_options': ['--disable-e10s'], 10:18:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:18:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:18:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:26 INFO - 'talos_options': ['--disable-e10s'], 10:18:26 INFO - 'tests': ['damp', 'tps']}, 10:18:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:26 INFO - 'tests': ['damp', 'tps']}, 10:18:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:18:26 INFO - 'tests': ['dromaeo_dom']}, 10:18:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:18:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:18:26 INFO - 'tests': ['basic_compositor_video']}, 10:18:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:18:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:18:26 INFO - 'tests': ['a11yr', 10:18:26 INFO - 'ts_paint', 10:18:26 INFO - 'tpaint', 10:18:26 INFO - 'sessionrestore', 10:18:26 INFO - 'sessionrestore_no_auto_restore', 10:18:26 INFO - 'tabpaint']}, 10:18:26 INFO - 'other-e10s': {'tests': ['a11yr', 10:18:26 INFO - 'ts_paint', 10:18:26 INFO - 'tpaint', 10:18:26 INFO - 'sessionrestore', 10:18:26 INFO - 'sessionrestore_no_auto_restore', 10:18:26 INFO - 'tabpaint']}, 10:18:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:18:26 INFO - 'ts_paint', 10:18:26 INFO - 'tpaint', 10:18:26 INFO - 'sessionrestore', 10:18:26 INFO - 'sessionrestore_no_auto_restore', 10:18:26 INFO - 'tabpaint']}, 10:18:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:18:26 INFO - 'ts_paint', 10:18:26 INFO - 'tpaint', 10:18:26 INFO - 'sessionrestore', 10:18:26 INFO - 'sessionrestore_no_auto_restore', 10:18:26 INFO - 'tabpaint']}, 10:18:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:18:26 INFO - 'tests': ['a11yr', 10:18:26 INFO - 'ts_paint', 10:18:26 INFO - 'tpaint', 10:18:26 INFO - 'sessionrestore', 10:18:26 INFO - 'sessionrestore_no_auto_restore', 10:18:26 INFO - 'tabpaint']}, 10:18:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:18:26 INFO - 'tests': ['a11yr', 10:18:26 INFO - 'ts_paint', 10:18:26 INFO - 'tpaint', 10:18:26 INFO - 'sessionrestore', 10:18:26 INFO - 'sessionrestore_no_auto_restore', 10:18:26 INFO - 'tabpaint']}, 10:18:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:18:26 INFO - 'tests': ['tsvgx', 10:18:26 INFO - 'tsvgr_opacity', 10:18:26 INFO - 'tart', 10:18:26 INFO - 'tscrollx', 10:18:26 INFO - 'cart']}, 10:18:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:18:26 INFO - 'tsvgr_opacity', 10:18:26 INFO - 'tart', 10:18:26 INFO - 'tscrollx', 10:18:26 INFO - 'cart']}, 10:18:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:26 INFO - 'talos_options': ['--disable-e10s'], 10:18:26 INFO - 'tests': ['tp5o']}, 10:18:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:26 INFO - 'tests': ['tp5o']}, 10:18:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:26 INFO - 'talos_options': ['--disable-e10s', 10:18:26 INFO - '--xperf_path', 10:18:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:18:26 INFO - 'tests': ['tp5n']}, 10:18:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:26 INFO - 'talos_options': ['--xperf_path', 10:18:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:18:26 INFO - 'tests': ['tp5n']}}, 10:18:26 INFO - 'talos.zip': {'path': '', 10:18:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:18:26 INFO - Running post-action listener: _resource_record_post_action 10:18:26 INFO - [mozharness: 2016-10-11 17:18:26.446282Z] Finished populate-webroot step (success) 10:18:26 INFO - [mozharness: 2016-10-11 17:18:26.446372Z] Running create-virtualenv step. 10:18:26 INFO - Running pre-action listener: _resource_record_pre_action 10:18:26 INFO - Running main action method: create_virtualenv 10:18:26 INFO - Creating virtualenv /builds/slave/test/build/venv 10:18:26 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 10:18:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:18:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:18:26 INFO - Using real prefix '/usr' 10:18:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:18:27 INFO - Installing distribute.............................................................................................................................................................................................done. 10:18:29 INFO - Installing pip.................done. 10:18:29 INFO - Return code: 0 10:18:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:18:29 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')]} 10:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:29 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')]} 10:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:29 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 0xbfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb93b0>, '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 0xeba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeba740>, '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': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', '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 10:18:29 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 10:18:29 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 10:18:29 INFO - Using env: {'DISPLAY': ':0', 10:18:29 INFO - 'HOME': '/home/cltbld', 10:18:29 INFO - 'LANG': 'en_US.UTF-8', 10:18:29 INFO - 'LANGUAGE': 'en_US:en', 10:18:29 INFO - 'LOGNAME': 'cltbld', 10:18:29 INFO - 'MAIL': '/var/mail/cltbld', 10:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:29 INFO - 'MOZ_NO_REMOTE': '1', 10:18:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:29 INFO - 'NO_EM_RESTART': '1', 10:18:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:29 INFO - 'PWD': '/builds/slave/test', 10:18:29 INFO - 'SHELL': '/bin/bash', 10:18:29 INFO - 'SHLVL': '1', 10:18:29 INFO - 'TERM': 'linux', 10:18:29 INFO - 'TMOUT': '86400', 10:18:29 INFO - 'USER': 'cltbld', 10:18:29 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', 10:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:29 INFO - '_': '/tools/buildbot/bin/python'} 10:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:29 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 10:18:29 INFO - Cleaning up... 10:18:29 INFO - Return code: 0 10:18:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:18:29 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')]} 10:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:29 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')]} 10:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:29 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 0xbfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb93b0>, '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 0xeba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeba740>, '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': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', '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 10:18:29 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 10:18:29 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 10:18:29 INFO - Using env: {'DISPLAY': ':0', 10:18:29 INFO - 'HOME': '/home/cltbld', 10:18:29 INFO - 'LANG': 'en_US.UTF-8', 10:18:29 INFO - 'LANGUAGE': 'en_US:en', 10:18:29 INFO - 'LOGNAME': 'cltbld', 10:18:29 INFO - 'MAIL': '/var/mail/cltbld', 10:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:29 INFO - 'MOZ_NO_REMOTE': '1', 10:18:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:29 INFO - 'NO_EM_RESTART': '1', 10:18:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:29 INFO - 'PWD': '/builds/slave/test', 10:18:29 INFO - 'SHELL': '/bin/bash', 10:18:29 INFO - 'SHLVL': '1', 10:18:29 INFO - 'TERM': 'linux', 10:18:29 INFO - 'TMOUT': '86400', 10:18:29 INFO - 'USER': 'cltbld', 10:18:29 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', 10:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:29 INFO - '_': '/tools/buildbot/bin/python'} 10:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:29 INFO - Downloading/unpacking psutil>=3.1.1 10:18:29 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 10:18:29 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 10:18:29 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 10:18:29 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 10:18:29 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 10:18:29 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 10:18:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:18:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:18:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:18:31 INFO - Installing collected packages: psutil 10:18:31 INFO - Running setup.py install for psutil 10:18:31 INFO - building 'psutil._psutil_linux' extension 10:18:31 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 10:18:32 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 10:18:33 INFO - building 'psutil._psutil_posix' extension 10:18:33 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 10:18:33 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 10:18:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:18:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:18:33 INFO - Successfully installed psutil 10:18:33 INFO - Cleaning up... 10:18:33 INFO - Return code: 0 10:18:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:18:33 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')]} 10:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:33 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')]} 10:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:33 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 0xbfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb93b0>, '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 0xeba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeba740>, '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': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', '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 10:18:33 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 10:18:33 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 10:18:33 INFO - Using env: {'DISPLAY': ':0', 10:18:33 INFO - 'HOME': '/home/cltbld', 10:18:33 INFO - 'LANG': 'en_US.UTF-8', 10:18:33 INFO - 'LANGUAGE': 'en_US:en', 10:18:33 INFO - 'LOGNAME': 'cltbld', 10:18:33 INFO - 'MAIL': '/var/mail/cltbld', 10:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:33 INFO - 'MOZ_NO_REMOTE': '1', 10:18:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:33 INFO - 'NO_EM_RESTART': '1', 10:18:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:33 INFO - 'PWD': '/builds/slave/test', 10:18:33 INFO - 'SHELL': '/bin/bash', 10:18:33 INFO - 'SHLVL': '1', 10:18:33 INFO - 'TERM': 'linux', 10:18:33 INFO - 'TMOUT': '86400', 10:18:33 INFO - 'USER': 'cltbld', 10:18:33 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', 10:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:33 INFO - '_': '/tools/buildbot/bin/python'} 10:18:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:18:35 INFO - Installing collected packages: mozsystemmonitor 10:18:35 INFO - Running setup.py install for mozsystemmonitor 10:18:35 INFO - Successfully installed mozsystemmonitor 10:18:35 INFO - Cleaning up... 10:18:35 INFO - Return code: 0 10:18:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb93b0>, '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 0xeba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeba740>, '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': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', '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 10:18:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:18:35 INFO - Using env: {'DISPLAY': ':0', 10:18:35 INFO - 'HOME': '/home/cltbld', 10:18:35 INFO - 'LANG': 'en_US.UTF-8', 10:18:35 INFO - 'LANGUAGE': 'en_US:en', 10:18:35 INFO - 'LOGNAME': 'cltbld', 10:18:35 INFO - 'MAIL': '/var/mail/cltbld', 10:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:35 INFO - 'MOZ_NO_REMOTE': '1', 10:18:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:35 INFO - 'NO_EM_RESTART': '1', 10:18:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:35 INFO - 'PWD': '/builds/slave/test', 10:18:35 INFO - 'SHELL': '/bin/bash', 10:18:35 INFO - 'SHLVL': '1', 10:18:35 INFO - 'TERM': 'linux', 10:18:35 INFO - 'TMOUT': '86400', 10:18:35 INFO - 'USER': 'cltbld', 10:18:35 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', 10:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:35 INFO - '_': '/tools/buildbot/bin/python'} 10:18:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:35 INFO - Downloading/unpacking jsonschema==2.5.1 10:18:35 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 10:18:35 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 10:18:35 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 10:18:35 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 10:18:35 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 10:18:35 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 10:18:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:18:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:18:37 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 10:18:37 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 10:18:37 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 10:18:37 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 10:18:37 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 10:18:37 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 10:18:38 INFO - Downloading functools32-3.2.3-2.tar.gz 10:18:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:18:38 INFO - warning: no files found matching '*.txt' 10:18:38 INFO - no previously-included directories found matching 'build' 10:18:38 INFO - no previously-included directories found matching 'dist' 10:18:38 INFO - no previously-included directories found matching '.git*' 10:18:38 INFO - Installing collected packages: jsonschema, functools32 10:18:38 INFO - Running setup.py install for functools32 10:18:38 INFO - warning: no files found matching '*.txt' 10:18:38 INFO - no previously-included directories found matching 'build' 10:18:38 INFO - no previously-included directories found matching 'dist' 10:18:38 INFO - no previously-included directories found matching '.git*' 10:18:38 INFO - Successfully installed jsonschema functools32 10:18:38 INFO - Cleaning up... 10:18:38 INFO - Return code: 0 10:18:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:18:38 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')]} 10:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:38 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')]} 10:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:38 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 0xbfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb93b0>, '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 0xeba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeba740>, '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': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', '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 10:18:38 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 10:18:38 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 10:18:38 INFO - Using env: {'DISPLAY': ':0', 10:18:38 INFO - 'HOME': '/home/cltbld', 10:18:38 INFO - 'LANG': 'en_US.UTF-8', 10:18:38 INFO - 'LANGUAGE': 'en_US:en', 10:18:38 INFO - 'LOGNAME': 'cltbld', 10:18:38 INFO - 'MAIL': '/var/mail/cltbld', 10:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:38 INFO - 'MOZ_NO_REMOTE': '1', 10:18:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:38 INFO - 'NO_EM_RESTART': '1', 10:18:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:38 INFO - 'PWD': '/builds/slave/test', 10:18:38 INFO - 'SHELL': '/bin/bash', 10:18:38 INFO - 'SHLVL': '1', 10:18:38 INFO - 'TERM': 'linux', 10:18:38 INFO - 'TMOUT': '86400', 10:18:38 INFO - 'USER': 'cltbld', 10:18:38 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', 10:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:38 INFO - '_': '/tools/buildbot/bin/python'} 10:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:18:38 INFO - Cleaning up... 10:18:38 INFO - Return code: 0 10:18:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:18:38 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')]} 10:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:38 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')]} 10:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:38 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 0xbfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb93b0>, '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 0xeba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeba740>, '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': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', '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 10:18:38 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 10:18:38 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 10:18:38 INFO - Using env: {'DISPLAY': ':0', 10:18:38 INFO - 'HOME': '/home/cltbld', 10:18:38 INFO - 'LANG': 'en_US.UTF-8', 10:18:38 INFO - 'LANGUAGE': 'en_US:en', 10:18:38 INFO - 'LOGNAME': 'cltbld', 10:18:38 INFO - 'MAIL': '/var/mail/cltbld', 10:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:38 INFO - 'MOZ_NO_REMOTE': '1', 10:18:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:38 INFO - 'NO_EM_RESTART': '1', 10:18:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:38 INFO - 'PWD': '/builds/slave/test', 10:18:38 INFO - 'SHELL': '/bin/bash', 10:18:38 INFO - 'SHLVL': '1', 10:18:38 INFO - 'TERM': 'linux', 10:18:38 INFO - 'TMOUT': '86400', 10:18:38 INFO - 'USER': 'cltbld', 10:18:38 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', 10:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:38 INFO - '_': '/tools/buildbot/bin/python'} 10:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:38 INFO - Downloading/unpacking blobuploader==1.2.4 10:18:38 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 10:18:38 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 10:18:38 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 10:18:38 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 10:18:38 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 10:18:38 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 10:18:40 INFO - Downloading blobuploader-1.2.4.tar.gz 10:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:18:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:18:40 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 10:18:40 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 10:18:40 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 10:18:40 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 10:18:40 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 10:18:40 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 10:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:18:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:41 INFO - Downloading docopt-0.6.1.tar.gz 10:18:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:18:41 INFO - Installing collected packages: blobuploader, requests, docopt 10:18:41 INFO - Running setup.py install for blobuploader 10:18:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:18:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:18:41 INFO - Running setup.py install for requests 10:18:41 INFO - Running setup.py install for docopt 10:18:42 INFO - Successfully installed blobuploader requests docopt 10:18:42 INFO - Cleaning up... 10:18:42 INFO - Return code: 0 10:18:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:42 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 0xbfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb93b0>, '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 0xeba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeba740>, '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': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', '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 10:18:42 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 10:18:42 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 10:18:42 INFO - Using env: {'DISPLAY': ':0', 10:18:42 INFO - 'HOME': '/home/cltbld', 10:18:42 INFO - 'LANG': 'en_US.UTF-8', 10:18:42 INFO - 'LANGUAGE': 'en_US:en', 10:18:42 INFO - 'LOGNAME': 'cltbld', 10:18:42 INFO - 'MAIL': '/var/mail/cltbld', 10:18:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:42 INFO - 'MOZ_NO_REMOTE': '1', 10:18:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:42 INFO - 'NO_EM_RESTART': '1', 10:18:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:42 INFO - 'PWD': '/builds/slave/test', 10:18:42 INFO - 'SHELL': '/bin/bash', 10:18:42 INFO - 'SHLVL': '1', 10:18:42 INFO - 'TERM': 'linux', 10:18:42 INFO - 'TMOUT': '86400', 10:18:42 INFO - 'USER': 'cltbld', 10:18:42 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', 10:18:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:42 INFO - '_': '/tools/buildbot/bin/python'} 10:18:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:18:42 INFO - Running setup.py (path:/tmp/pip-S6GQ7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:18:42 INFO - Running setup.py (path:/tmp/pip-0buSxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:18:42 INFO - Running setup.py (path:/tmp/pip-38V_EX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:18:42 INFO - Running setup.py (path:/tmp/pip-KbUm7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:18:42 INFO - Running setup.py (path:/tmp/pip-94r_xw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:18:42 INFO - Running setup.py (path:/tmp/pip-5wxKnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:18:42 INFO - Running setup.py (path:/tmp/pip-7ss6aH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:18:42 INFO - Running setup.py (path:/tmp/pip-vhDnzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:18:42 INFO - Running setup.py (path:/tmp/pip-IlK2Dm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:18:42 INFO - Running setup.py (path:/tmp/pip-YlQiGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:18:42 INFO - Running setup.py (path:/tmp/pip-9HQAdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:18:43 INFO - Running setup.py (path:/tmp/pip-OIIO8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:18:43 INFO - Running setup.py (path:/tmp/pip-DNHwo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:18:43 INFO - Running setup.py (path:/tmp/pip-oh28bJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:18:43 INFO - Running setup.py (path:/tmp/pip-dZFcSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:18:43 INFO - Running setup.py (path:/tmp/pip-mP27WX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:18:43 INFO - Running setup.py (path:/tmp/pip-0XE8le-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:18:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:18:43 INFO - Running setup.py install for manifestparser 10:18:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:18:43 INFO - Running setup.py install for mozcrash 10:18:43 INFO - Running setup.py install for mozdebug 10:18:43 INFO - Running setup.py install for mozdevice 10:18:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:18:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:18:43 INFO - Running setup.py install for mozfile 10:18:44 INFO - Running setup.py install for mozhttpd 10:18:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Running setup.py install for mozinfo 10:18:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Running setup.py install for mozInstall 10:18:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Running setup.py install for mozleak 10:18:44 INFO - Running setup.py install for mozlog 10:18:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Running setup.py install for moznetwork 10:18:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Running setup.py install for mozprocess 10:18:44 INFO - Running setup.py install for mozprofile 10:18:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Running setup.py install for mozrunner 10:18:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Running setup.py install for mozscreenshot 10:18:45 INFO - Running setup.py install for moztest 10:18:45 INFO - Running setup.py install for mozversion 10:18:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:18:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:18:45 INFO - Cleaning up... 10:18:45 INFO - Return code: 0 10:18:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:18:45 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')]} 10:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:45 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')]} 10:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:45 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 0xbfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb93b0>, '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 0xeba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeba740>, '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': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', '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 10:18:45 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 10:18:45 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 10:18:45 INFO - Using env: {'DISPLAY': ':0', 10:18:45 INFO - 'HOME': '/home/cltbld', 10:18:45 INFO - 'LANG': 'en_US.UTF-8', 10:18:45 INFO - 'LANGUAGE': 'en_US:en', 10:18:45 INFO - 'LOGNAME': 'cltbld', 10:18:45 INFO - 'MAIL': '/var/mail/cltbld', 10:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:45 INFO - 'MOZ_NO_REMOTE': '1', 10:18:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:45 INFO - 'NO_EM_RESTART': '1', 10:18:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:45 INFO - 'PWD': '/builds/slave/test', 10:18:45 INFO - 'SHELL': '/bin/bash', 10:18:45 INFO - 'SHLVL': '1', 10:18:45 INFO - 'TERM': 'linux', 10:18:45 INFO - 'TMOUT': '86400', 10:18:45 INFO - 'USER': 'cltbld', 10:18:45 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', 10:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:45 INFO - '_': '/tools/buildbot/bin/python'} 10:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:18:45 INFO - Running setup.py (path:/tmp/pip-u6ebn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:18:45 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)) 10:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:18:45 INFO - Running setup.py (path:/tmp/pip-oyIkxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:18:45 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)) 10:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:18:45 INFO - Running setup.py (path:/tmp/pip-OE9xA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:18:45 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)) 10:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:18:45 INFO - Running setup.py (path:/tmp/pip-wxfN7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:18:45 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)) 10:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:18:45 INFO - Running setup.py (path:/tmp/pip-ch9G3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:18:45 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)) 10:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:18:45 INFO - Running setup.py (path:/tmp/pip-C5sdbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:18:45 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)) 10:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:18:45 INFO - Running setup.py (path:/tmp/pip-zYM66J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:18:46 INFO - Running setup.py (path:/tmp/pip-r6GkES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:18:46 INFO - Running setup.py (path:/tmp/pip-Rwynlv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:18:46 INFO - Running setup.py (path:/tmp/pip-rumR2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:18:46 INFO - Running setup.py (path:/tmp/pip-1oB8jH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:18:46 INFO - Running setup.py (path:/tmp/pip-9_u9W4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:18:46 INFO - Running setup.py (path:/tmp/pip-8j5Xi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:18:46 INFO - Running setup.py (path:/tmp/pip-AxEBVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:18:46 INFO - Running setup.py (path:/tmp/pip-Cde0th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:18:46 INFO - Running setup.py (path:/tmp/pip-Po3BJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:18:46 INFO - Running setup.py (path:/tmp/pip-tOE5Z7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:18:46 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)) 10:18:46 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)) 10:18:46 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)) 10:18:46 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)) 10:18:46 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)) 10:18:46 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)) 10:18:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:48 INFO - Downloading blessings-1.6.tar.gz 10:18:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:18:48 INFO - Installing collected packages: blessings 10:18:48 INFO - Running setup.py install for blessings 10:18:48 INFO - Successfully installed blessings 10:18:48 INFO - Cleaning up... 10:18:48 INFO - Return code: 0 10:18:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:18:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:18:49 INFO - Reading from file tmpfile_stdout 10:18:49 INFO - Current package versions: 10:18:49 INFO - argparse == 1.2.1 10:18:49 INFO - blessings == 1.6 10:18:49 INFO - blobuploader == 1.2.4 10:18:49 INFO - docopt == 0.6.1 10:18:49 INFO - functools32 == 3.2.3-2 10:18:49 INFO - jsonschema == 2.5.1 10:18:49 INFO - manifestparser == 1.1 10:18:49 INFO - mozInstall == 1.12 10:18:49 INFO - mozcrash == 0.17 10:18:49 INFO - mozdebug == 0.1 10:18:49 INFO - mozdevice == 0.48 10:18:49 INFO - mozfile == 1.2 10:18:49 INFO - mozhttpd == 0.7 10:18:49 INFO - mozinfo == 0.9 10:18:49 INFO - mozleak == 0.1 10:18:49 INFO - mozlog == 3.3 10:18:49 INFO - moznetwork == 0.27 10:18:49 INFO - mozprocess == 0.23 10:18:49 INFO - mozprofile == 0.28 10:18:49 INFO - mozrunner == 6.12 10:18:49 INFO - mozscreenshot == 0.1 10:18:49 INFO - mozsystemmonitor == 0.3 10:18:49 INFO - moztest == 0.7 10:18:49 INFO - mozversion == 1.4 10:18:49 INFO - psutil == 3.1.1 10:18:49 INFO - requests == 1.2.3 10:18:49 INFO - wsgiref == 0.1.2 10:18:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:49 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 0xbfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb93b0>, '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 0xeba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeba740>, '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': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', '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 10:18:49 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 10:18:49 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 10:18:49 INFO - Using env: {'DISPLAY': ':0', 10:18:49 INFO - 'HOME': '/home/cltbld', 10:18:49 INFO - 'LANG': 'en_US.UTF-8', 10:18:49 INFO - 'LANGUAGE': 'en_US:en', 10:18:49 INFO - 'LOGNAME': 'cltbld', 10:18:49 INFO - 'MAIL': '/var/mail/cltbld', 10:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:49 INFO - 'MOZ_NO_REMOTE': '1', 10:18:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:49 INFO - 'NO_EM_RESTART': '1', 10:18:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:49 INFO - 'PWD': '/builds/slave/test', 10:18:49 INFO - 'SHELL': '/bin/bash', 10:18:49 INFO - 'SHLVL': '1', 10:18:49 INFO - 'TERM': 'linux', 10:18:49 INFO - 'TMOUT': '86400', 10:18:49 INFO - 'USER': 'cltbld', 10:18:49 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', 10:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:49 INFO - '_': '/tools/buildbot/bin/python'} 10:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 INFO - Cleaning up... 10:18:49 INFO - Return code: 0 10:18:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb93b0>, '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 0xeba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeba740>, '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': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', '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 10:18:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:18:49 INFO - Using env: {'DISPLAY': ':0', 10:18:49 INFO - 'HOME': '/home/cltbld', 10:18:49 INFO - 'LANG': 'en_US.UTF-8', 10:18:49 INFO - 'LANGUAGE': 'en_US:en', 10:18:49 INFO - 'LOGNAME': 'cltbld', 10:18:49 INFO - 'MAIL': '/var/mail/cltbld', 10:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:49 INFO - 'MOZ_NO_REMOTE': '1', 10:18:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:49 INFO - 'NO_EM_RESTART': '1', 10:18:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:49 INFO - 'PWD': '/builds/slave/test', 10:18:49 INFO - 'SHELL': '/bin/bash', 10:18:49 INFO - 'SHLVL': '1', 10:18:49 INFO - 'TERM': 'linux', 10:18:49 INFO - 'TMOUT': '86400', 10:18:49 INFO - 'USER': 'cltbld', 10:18:49 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', 10:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:49 INFO - '_': '/tools/buildbot/bin/python'} 10:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:18:49 INFO - Cleaning up... 10:18:49 INFO - Return code: 0 10:18:49 INFO - Running post-action listener: _resource_record_post_action 10:18:49 INFO - Running post-action listener: _start_resource_monitoring 10:18:49 INFO - Starting resource monitoring. 10:18:49 INFO - [mozharness: 2016-10-11 17:18:49.626792Z] Finished create-virtualenv step (success) 10:18:49 INFO - [mozharness: 2016-10-11 17:18:49.627767Z] Running install step. 10:18:49 INFO - Running pre-action listener: _resource_record_pre_action 10:18:49 INFO - Running main action method: install 10:18:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:18:49 INFO - Reading from file tmpfile_stdout 10:18:49 INFO - Detecting whether we're running mozinstall >=1.0... 10:18:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:18:49 INFO - Reading from file tmpfile_stdout 10:18:49 INFO - Output received: 10:18:49 INFO - Usage: mozinstall [options] installer 10:18:49 INFO - Options: 10:18:49 INFO - -h, --help show this help message and exit 10:18:49 INFO - -d DEST, --destination=DEST 10:18:49 INFO - Directory to install application into. [default: 10:18:49 INFO - "/builds/slave/test"] 10:18:49 INFO - --app=APP Application being installed. [default: firefox] 10:18:49 INFO - mkdir: /builds/slave/test/build/application 10:18:49 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'] 10:18:49 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 10:19:04 INFO - Reading from file tmpfile_stdout 10:19:04 INFO - Output received: 10:19:04 INFO - /builds/slave/test/build/application/firefox/firefox 10:19:04 INFO - Running post-action listener: _resource_record_post_action 10:19:04 INFO - [mozharness: 2016-10-11 17:19:04.097618Z] Finished install step (success) 10:19:04 INFO - [mozharness: 2016-10-11 17:19:04.097894Z] Running run-tests step. 10:19:04 INFO - Running pre-action listener: _resource_record_pre_action 10:19:04 INFO - Running main action method: run_tests 10:19:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:19:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:19:04 INFO - Python 2.7.3 10:19:04 INFO - Return code: 0 10:19:04 INFO - grabbing minidump binary from tooltool 10:19: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')]} 10:19:04 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 0xeb93b0>, '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 0xeba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeba740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:19:04 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 10:19:04 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 10:19:04 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 10:19:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:19:04 INFO - Return code: 0 10:19:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:19:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:19:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:19:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:19:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:19:04 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/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 10:19:04 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/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 10:19:04 INFO - Using env: {'DISPLAY': ':0', 10:19:04 INFO - 'HOME': '/home/cltbld', 10:19:04 INFO - 'LANG': 'en_US.UTF-8', 10:19:04 INFO - 'LANGUAGE': 'en_US:en', 10:19:04 INFO - 'LOGNAME': 'cltbld', 10:19:04 INFO - 'MAIL': '/var/mail/cltbld', 10:19:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:19:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:04 INFO - 'MOZ_NO_REMOTE': '1', 10:19:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:19:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:19:04 INFO - 'NO_EM_RESTART': '1', 10:19:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:19:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:04 INFO - 'PWD': '/builds/slave/test', 10:19:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:19:04 INFO - 'SHELL': '/bin/bash', 10:19:04 INFO - 'SHLVL': '1', 10:19:04 INFO - 'TERM': 'linux', 10:19:04 INFO - 'TMOUT': '86400', 10:19:04 INFO - 'USER': 'cltbld', 10:19:04 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466', 10:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:04 INFO - '_': '/tools/buildbot/bin/python'} 10:19:04 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/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 10:19:04 INFO - mozversion application_buildid: 20161011072046 10:19:04 INFO - mozversion application_changeset: d1b244e09392f8d17ae23403662dedcbce10eea9 10:19:04 INFO - mozversion application_display_name: Firefox 10:19:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:19:04 INFO - mozversion application_name: Firefox 10:19:04 INFO - mozversion application_remotingname: firefox 10:19:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:19:04 INFO - mozversion application_vendor: Mozilla 10:19:04 INFO - mozversion application_version: 50.0 10:19:04 INFO - mozversion platform_buildid: 20161011072046 10:19:04 INFO - mozversion platform_changeset: d1b244e09392f8d17ae23403662dedcbce10eea9 10:19:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:19:04 INFO - mozversion platform_version: 50.0 10:19:04 INFO - using testdate: 1476206344 10:19:04 INFO - actual date: 1476206344 10:19:04 INFO - starting webserver on 'localhost:42078' 10:19:04 INFO - SUITE-START | Running 2 tests 10:19:04 INFO - TEST-START | dromaeo_css 10:19:04 INFO - operating with platform_type : linux_ 10:19:04 INFO - Initialising browser for dromaeo_css test... 10:19:04 INFO - TEST-INFO | started process 3309 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjsHHnX/profile http://localhost:42078/getInfo.html) 10:19:08 INFO - PROCESS | 3309 | __metrics Screen width/height:1600/1200 10:19:08 INFO - PROCESS | 3309 | colorDepth:24 10:19:08 INFO - PROCESS | 3309 | Browser inner width/height: 1024/697 10:19:08 INFO - PROCESS | 3309 | __metrics 10:19:09 INFO - PROCESS | 3309 | 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" 10:19:09 INFO - PROCESS | 3309 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:19:10 INFO - TEST-INFO | 3309: exit 0 10:19:10 INFO - Browser initialized. 10:19:10 INFO - Running cycle 1/1 for dromaeo_css test... 10:19:10 INFO - TEST-INFO | started process 3398 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjsHHnX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:19:22 INFO - PROCESS | 3398 | RSS: Main: 144326656 10:19:22 INFO - PROCESS | 3398 | 10:20:14 INFO - PROCESS | 3398 | 1476206414205 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] 10:22:22 INFO - PROCESS | 3398 | Cycle 1(1): loaded http://localhost:42078/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42078/tests/dromaeo/cssquery-ext.html) 10:22:23 INFO - PROCESS | 3398 | RSS: Main: 241422336 10:22:23 INFO - PROCESS | 3398 | 10:25:25 INFO - PROCESS | 3398 | Cycle 1(1): loaded http://localhost:42078/tests/dromaeo/cssquery-ext.html (next: http://localhost:42078/tests/dromaeo/cssquery-jquery.html) 10:25:26 INFO - PROCESS | 3398 | RSS: Main: 230539264 10:25:26 INFO - PROCESS | 3398 | 10:28:28 INFO - PROCESS | 3398 | Cycle 1(1): loaded http://localhost:42078/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42078/tests/dromaeo/cssquery-mootools.html) 10:28:28 INFO - PROCESS | 3398 | RSS: Main: 175964160 10:28:28 INFO - PROCESS | 3398 | 10:31:30 INFO - PROCESS | 3398 | Cycle 1(1): loaded http://localhost:42078/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42078/tests/dromaeo/cssquery-prototype.html) 10:31:31 INFO - PROCESS | 3398 | RSS: Main: 176738304 10:31:31 INFO - PROCESS | 3398 | 10:34:33 INFO - PROCESS | 3398 | Cycle 1(1): loaded http://localhost:42078/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42078/tests/dromaeo/cssquery-yui.html) 10:34:34 INFO - PROCESS | 3398 | RSS: Main: 177369088 10:34:34 INFO - PROCESS | 3398 | 10:37:37 INFO - PROCESS | 3398 | Cycle 1(1): loaded http://localhost:42078/tests/dromaeo/cssquery-yui.html (next: http://localhost:42078/tests/dromaeo/cssquery-dojo.html) 10:37:37 INFO - PROCESS | 3398 | RSS: Main: 269668352 10:37:37 INFO - PROCESS | 3398 | 10:37:37 INFO - PROCESS | 3398 | __start_tp_report 10:37:37 INFO - PROCESS | 3398 | _x_x_mozilla_page_load 10:37:37 INFO - PROCESS | 3398 | _x_x_mozilla_page_load_details 10:37:37 INFO - PROCESS | 3398 | |i|pagename|runs| 10:37:37 INFO - PROCESS | 3398 | |0;dojo.html;3138.8744450221993;3154.3060526684135;3162.5888634477524;3194.536792165136;3212.3575284943017;5465.371482279534;5566.471185237401;5584.134459158837;5590.524805391537;5618.073017952033;5579.553635709143;5605.803796867111;5642;5660.037793575092;5685.971570142154;5096.643234973553;5113.872153196163;5150.8712282193055;5177.352830896138;5187.507186817246;4965.851024469272;5037.345145131146;5179.689218646893;5186.507281808223;5189.584833213334;4973.254011898226;4998.300237966688;5024.171011783052;5060.06388818068;5094.61790365724;5098.311727916739;5137.871553211162;5218.712970786605;5219.530242278213;5255.264263003182;4865.70805751656;5102.668326558799;5120.92460583278;5160.896782064376;5180.093483640348;5421.918671219895;5435.374931882805;5452.04589196889;5452.100403433429;5471.06991811393;5860.677662728548;5911.29064512257;5966.820995370146;5971.343152253248;5972.64164150148;5866.76532938682;5945.791897283618;5998.880022399571;6006.00900851359;6009.699515024231;5543.085390910495;5648.576356773299;5664.54683625309;5696.544276457874;5704.030314846486;6158.107074474176;6196.287426945869;6209.130721698965;6209.6895155242055;6210.285817130997;5734.111212761945;5807.419258074243;5864.765409383662;5954.8809023819285;5966.791162309299;5984.222051133324;6003.849903752441;6005.3394126646035;6025.246844144482;6040.486558642477;5977.3126090500145;5983.311919129269;5988.760449581968;5994.130851026577;5995.790147344822;5706.115552089433;5810.738516766755;5812.9709351452975;5995.52035837132;5999.550033747487;4528.388667529841;4530.932036019462;4532.093581283756;4533.909321813545;4539.682222244412;4006.1787333596544;4044.352903535479;4063.7358571692744;4068.572799856032;4086.203190377848;5735.369109398046;5749.88500229993;5750.654960702372;5754.88490230193;5757.9424205757405;6006.789762358297;6018.247719035121;6020.548458865603;6023.819285421444;6026.849328766816;5538.446155384429;5543.805966791224;5573.944260557342;5584.91622625661;5701.116326969326;5113.130767769489;5122.513361230677;5123.718195499208;5125.410577783528;5148.4079330876675;5997.370276121032;6079.6960151992225;6098.542609304409;6106.175666284995;6107.389261073857;5804.564657650609;5859.882802344015;5880.941190588211;5895.174675545341;5902.2327097477055;6133.202683651275;6137.101203255209;6146.784862529968;6156.015037593964;6156.1381406604005;5836.591438599427;5841.269841269841;5845.1232315153065;5848.385919478564;5881.7647294109465;5742.196092546963;5842.620229685057;5867.178594996619;5887.7056147193325;5937.3468918418075;5756.136579513107;5814.186013958134;5832.854178645568;5840.678762668094;5862.267216597926;5796.362400136066;5824.534037276923;5845.152452894392;5845.503132233808;5847.883042339045;5689.118186681071;5760.251167348218;5772.076467765138;5806.187133801355;5860.531157507475;5938.881222375442;5940.495057920125;5943.4948029416255;5957.523398128053;5969.224000879858;5785.710714464176;5804.535637148933;5808.07070868659;5809.767609295581;5839.065749480063 10:37:37 INFO - PROCESS | 3398 | |1;ext.html;593.273240280656;624.4879199056774;643.3437893348781;649.8635286589816;650.7917466410747;37910;46825.29762053572;47975.52024479754;48029.759851200695;52469.21296180551;49701;50212.49787502124;50336;50354.49645503544;50438.243426348636;8310.335173186131;9260.722178334661;9417.340786144972;9423.34036617437;9427.387219830724;10972.23194376394;11275.774484510304;11278.548858045668;11565.363904985223;11578.884211157907;11306.491207895624;11402.258853174517;11406.885931140707;11413.25813822103;11415.25800822948;11270.042046426022;11343.319400835977;11343.773124537545;11346;11352.467416898695;11064.446777661085;11108.500117494732;11145.331280123219;11149.052330551913;11150.107991360672;46267.53732462666;46588.53411465876;46628.53371466276;46655.30017049747;46675.766621167015;3756.7745935243975;3769;3829.253295607358;3875.3411919973673;3967.9008024799323;35899.4615080774;35963.28073438543;36323.27353452916;37320.440193397124;37344.81327593345;4743.810247590127;4819.08437396897;4855.24743664729;4902.313676085351;5034.647574669774;410508;410842;413438;414462;417247;70070.64964675144;70467.29532704607;70656.64671676609;70718.87843364707;70722.64638676774;41049;42075.78962105231;42091.78954105271;44418.333724994154;44473;20312.69530957037;20512;20517.487062823548;20530.69203961942;20534.281300154424;13888.513902013381;14643.487477938434;15565.688686226438;15673.294701738449;15692.529224123295;20507.69238461424;21486.462838429165;21536.569268614854;21541.67687484689;21568.784312156677;7296.233895441009;7513.586752728543;7650.540967541965;7678.884816727754;7704.9614751927;1035.8705161854768;1126.2510430563584;1156.5373850459714;1220.3166226912956;1227.5580790915262;36051.4592281116;36100.81949590235;36180;36416;36473;1107.2526044919807;1160.431388619587;1168.4683469021575;1180.9173357864868;1194.9820752688718;18679;18883.62232755337;18903.243870245034;18904.52738681544;19022.80977190238;625336;629368;631045;635674;636725;66700.66649666721;66965;67269;67281;67387.66306168537;1347.4273433790795;1442.4374493947369;1477.0842077911764;1494.3873012065;1508.630385555546;33571.16072098119;36196.45705314475;36474;36670.633293666724;36913.815430922674;57145;58112.70943645255;58199.21705089325;58199.70900145642;58226.417735823794;451024;454484;454697;454856;489195;31552.526712099796;31586.684133158375;31669.524957125202;33638.83180584081;33900.66099339074;54102;54533.454665454425;54734.45265547294;54806.45193548014;56104.71947640236;44511.21955121759;46508;47453.52546474629;47711.28433073576;49038;45124.17230935163;45580.31629525493;46749.76625116989;46827.29759053685;48539.75730121327;45671;45950.54049459463;46122.308165378214;46172.30741538947;46896.531034689215;38959.415608765325;40398.1920361597;40628.59371406248;40657.39013914735;41473.1705365897;30298.098380953314;30352;30535.389292213586;30578.388432231677;30882.227944301572 10:37:37 INFO - PROCESS | 3398 | |2;jquery.html;419.4379531427886;421.7848897062497;428.09457996337744;430.91597138557984;443.4611946485022;18582.535436614075;20258.69611955822;20699.275525356614;21428.4642883928;21599.136034558636;20051.498712532193;20398.184072637076;20801;20947.476313092182;21135.894320528376;5814.418558144183;6018.6990650467305;6021.42796434338;6035.064564992411;6037.124616930542;5538.695371754552;5771.3074431068335;5797.5651826113;5884.852878678024;5902.173695682608;5880.353161152269;5885.234919460464;5890.204822348992;6331.14529538511;6386.0740192672265;5853.473187413153;6112.541559383064;6207.658578778165;6349.079383489391;6350.142730731362;5877.735501902382;5984.281886173687;5990.460858522711;6000.459958603747;6001.879962400772;13304.733905321937;13715.657108572366;14115.505957291549;14219.075760075666;14270.143791372448;2405.7594240575977;2441.755824417544;2459.114718701266;2468.642046903207;2487.589547724623;10471.005254500811;10959.890401095967;14006.92996535021;15047.699046019128;15208.543743687707;2023.1603884387962;2068.927587534429;2194.6817711431754;2210.767869373709;2233.117918422214;67432.662836686;75701;84751.57624211839;86718;88604;11768.70578235551;11858.288502689693;11965;11971.401429928468;12227.510899564095;17155.313787448362;17185.91407042982;17236.913815430842;17238.138093095295;17253.137343132792;9076.81846363069;9230.492322922304;9292.535373231447;9302.348835581579;9354.345195836362;8962.73111806647;9011.774705632411;9019.458832470073;9192.724218273463;9221;5028.446870844205;5077.654421578285;5144.331236939174;5153.175491921274;5268.341457317835;2044.1516770540059;2066.7519897612083;2084.4059443058654;2088.853780235369;2100.191426300855;962.8892677342055;1040.3393844908387;1071.7534966957644;1089.7827891876823;1128.3230061962756;20190.697139542917;20349.389518314474;20373.18507259693;20656.863872487156;20928.79071209299;1005.6882366466418;1032.3238278927315;1050.8003479338902;1080.9840544879855;1085.1318944844093;10646.574137034431;11593.6521904343;11654.475548600334;11655.242409243372;11697.473613687405;488779;498718;499134;505108;528007;75400.62299688466;92195;94671;97241.513792432;97564;989.5547003848297;996.2179688944144;1006.8087063458066;1023.8976102389702;1025.9230557708051;17963;18035;18505.415555434884;18757.812421875606;18970.051497424796;40282.79858600688;40649.59350406458;43163.78418108015;43522.564774353115;45831.77084114558;55634.72182639061;61664.38335616766;62621;63320;63900.36099638944;8789.428687135316;8843.159899809507;8907.51008694528;8970.282377409661;9010.459372437675;13591.592252232449;13948.860511394756;14076.507322243673;14274.500392486214;14607.19660418687;11831.17181797266;12337.691557711129;12566.560170393992;12578;12798.296093714935;12107.636770896886;12233.632991010283;12321.383930803244;12356.25862448256;12566.748665026465;6370.585911915896;11415;12079;12269.447874845831;12487;8007.439479236397;8913.242374398249;9054.366194366332;9057.230135438298;9370.101076456822;10058.899411005796;11086.944565277123;11265.26775759573;11794.705132371759;12172.75654486923 10:37:37 INFO - PROCESS | 3398 | |3;mootools.html;1574.2915687940422;1603.2945503978249;1642.8275031121732;1707.6926153292402;1793.8206179382055;8818.4708917465;9487.573059212335;9543.093406126407;9545;9555.37890037148;8154.592270386486;8726.38915275931;9051.230645395133;9074.72775816725;9310.255179585634;2461.606143017122;2500.962485562718;2508.9623655645178;2511.1336588876807;2526.3305224115625;2437.8659173745436;2526.861022643754;2528.4184637533376;2533.7086235083016;2533.949321013579;2455.533448644761;2473.8144639152138;2495.1142344467726;2495.6506089147533;2496.0639760089966;2399.7960173385195;2404.495056038234;2411.6141417373224;2441.8412803167917;2469.493753780471;2429.5626787178303;2430.1008626808016;2436.476157626119;2442.572549803795;2465.161844972718;9400.341976061678;9418.62325506979;9465.148136667663;9474.431534107976;9493.857592136124;5525.0883604205255;5657.462541058634;5659.632123911973;5664.348599911022;5665.830025099253;3607.152319204985;3679.061839230979;3744.3821769408014;3750.5311836020496;3760.849566017356;1144.4907016377715;1199.9880001199963;1224.2960297828783;1225.277086518949;1232.0882546915307;13214.471421143142;13237.86762132376;13246.33768311571;13258.66853328665;13266;4836;4891.73095479752;4982.825601103944;5037.546620804145;5037.596992240612;11041.613543525938;11056.391898445663;11073;11078.833817492745;11079.058280046125;5669.092945128759;5692.430756924357;5728.656280623176;5729.398413166641;5750.597458177887;4512.52618475062;4601.194790911577;5239.502247286502;5246.895062098737;5259.947400525945;4196.916061678749;4265;4280.293751531025;4291.678124140702;4346.5653434656915;1300.2718477652545;1304.5499302740538;1315.3817705678316;1337.9665508362175;1354;919.9420666233751;979.6228452045871;1030.9639162629271;1036.4506811389974;1046.3355917901251;6144.354842741537;6270.843228919222;6388.488920886411;6420.550561460653;6491.415772580491;470.45897218199343;476.768767147933;481.2084121619916;485.7328469341848;507.49504243277437;4235.195312890541;4388.705331927089;4401.84593539231;4435.4233949587;4528.184926713157;11241.94379028116;11761.117916156323;11773.058155347551;11775.941120294343;11776.941115294369;11416.37209953444;11433.771324573461;11592.130590205768;11619.535218591162;11622.88377116235;8734.257588104954;8746.169113934166;8770.736877893672;8815.426997245158;8828.293736501064;6339.302676705466;6363.93636063652;6429.132067171059;6467.676616169078;6487.513436492376;10538.8419173714;10757.085647720032;10780.137588993019;10878.401687906924;10887.455627218449;10760.46197690128;10773.29973551748;10886.891131088587;10924;10930.836037459285;7488.288612581796;7995.720149794729;8159.673613055649;8337.874931875906;8398.916010839814;9004.234640055669;9106.544672766468;9114.544272786201;9118.133777291147;9121.407108537924;7947.682092716458;8002.239787220205;8081.636326365446;8165.836683266182;8175.059868115243;7732.497387669783;7863.095743989515;7871.84256314882;7951.443398962017;7996.200379961957;6882.552634078969;7082.752103676346;7186.461015423969;7190.4607154464675;7192.640367981476;5620.775168993195;5676.375598684059;5740.0815869460685;5743.6840973745175;5762.078067509178;5447.836564903059;5470.097433923358;5473.67157970523;5572.247746554163;5625.46558076982 10:37:37 INFO - PROCESS | 3398 | |4;prototype.html;354.73040489228185;355.51294726225075;356.613074813827;357.6584361934353;366.2217787202194;5466.535344495717;5468.890622187554;5480.588955828314;5493.533049690771;5536.612437129403;5384.057789886764;5434.402215756264;5459.836204913849;5464;5465.672059676423;3774.622537746231;4119.382092686099;4228.302330115527;4268.295731204347;4337.783110844461;4315.611594956454;4321.308590625501;4325.74045557266;4335.284678028122;4350.7607081610495;4318.740875547462;4323.308270676693;4329.372241025049;4332.718373305741;4334.634590104123;4079.2861249281555;4250.659947204216;4297.839583312488;4322.524522302544;4334.523202447728;3793.468914351993;3794.6395092466173;3798.5631652360057;3799.639034291738;3807.505024346845;5283.260343551906;5294.470552944713;5314.176302673091;5325.121354976424;5346.893062138735;4766.713997160146;4830.450103317145;4845.806167753286;4849.199882019462;4895.9755201224125;5673.212937924541;5673.6595804251465;5685.573581981326;5686.658800471985;5696.829095127153;4157.210130075307;4166.1251137261215;4168.374743788426;4174;4177.248095342837;5657.943420565783;5834.424705329538;5835.679037652927;5840.766369345221;5876.500497457721;4199.4120823084795;4220.409142720022;4227.619514243733;4232.830686772495;4234.661227101825;4267.573242675708;4285.357196420562;4289.1421715657;4294.82820687169;4297.097609501977;4685.5782979532005;4816.133096042746;4834.927476087862;4851.442084160367;4855.635827312895;5606.495415412633;5633.155026746021;5636.872625474922;5644.774209031675;5681.602287839894;1475.3582191746625;1488.7543555313468;1494.2454060699276;1494.8355680875095;1495.865372116493;915.6428992692855;958.7105343313233;958.9786876975999;959.5777857742571;969.3941286695815;5012.899742005139;5013.1978883379215;5016.219242169051;5025.617955062387;5030.968651426449;5164.251183578435;5182.144946083854;5205.583553315726;5212.504812042926;5219.347581552306;4954.876128096755;4975.676581022294;5017.89964200714;5261.289725887033;5617.297837770278;3129.702678245563;3137.341158356771;3138.4821504451966;3158.6367567730003;3160.4627213373783;6104.4505994460715;6108.5723999319625;6108.6866323740605;6119.755209791648;6137.9079313810325;5757.481826635623;5758.337791153987;6020.337762846083;6037.9396206038255;6050.818475445656;5899.705014749331;6089.269287685506;6090.568716351654;6120.7551697931585;6123.38766123393;5923.229980102559;5926.170336153028;5930.940690593039;5933.436323549256;5944.405559444021;5615.410381909841;5840.853978650568;5857.355690873916;5879.118132280021;5884.617499862496;6155.784547540815;6155.907661385172;6158.414950579688;6166.784162554289;6176.783812566719;4054.39184122384;4257.297545904891;4259.297215959332;4263.55232700562;4263.827447451976;5882.176495290577;5889.672335130809;5890.410958904075;5895.410458954242;5895.64626122434;5756.56825738063;5763.308402991668;5764.106563482667;5774.682392468287;5792.275965504312;5589.55283577321;5769.09619825465;5769.624974376652;5776.306843178845;5789.681567513827;5759.65442073477;5770.971145144247;5779.797707080232;5787.131930210502;5791.218185545066;5586.497215250647;5733.627314224729;5801.245838041028;5809.50619197373;5821.214136091743;5613.775448981995;5628.846086552328;5633.802816901389;5639.718014099197;5643.548516118619 10:37:37 INFO - PROCESS | 3398 | |5;yui.html;769.6036541181293;792.2236208515654;799.1728560939429;834.2741814621279;918.6646873891032;16927.915360423198;17410.477685669433;17756.556086097855;17925.372611958584;18068.457946261617;16131.516054518383;16291.83708162918;16315.755263671055;16316.83683163171;16335.26491307891;748.5059860492076;878.3105262369041;885.8405487012351;897.5198268926126;900.3967341880923;895.8611415230649;899.7795540092686;904.4573256046385;904.4799240436743;905.1220316293185;949.6059135458811;953.31361419778;954.1079091049855;954.2699834626517;955.1594596754846;948.0850978805416;949.767307009787;950.1591091883698;950.4202436513721;950.4582388038821;696.6203419136583;699.5487910297829;700.4186525184085;701.2951983256845;701.7894631610548;14280.357383917644;14312.42750289987;14317.642058948506;14335.92832035833;14350.64123396913;14544;14666.56000319992;15123.773143402857;15333.386664533406;16470.505884823473;14218.928905355408;14413.305200446817;14424.639384015378;15014.474493392789;15016.774748378783;4834.395700537433;4837.419509658828;4872.926906096412;4997.225430058311;5035.0685123252315;92901;93033.53483232608;93359.53320233355;94193;94286;12275.74874897318;12444.31556264396;12496.562620308245;12602.747945041048;12796.744065118646;21893;22671.659925101136;22820.31539053831;22832.429189270402;22866.657000145013;9260.49067301305;9313.720588382359;9370.625174992923;9474.242060635133;9705.272104592184;4747.667663263538;4944.9258261126115;5027.79888804451;5066.442691303953;5075.771590278447;3865.9226815463535;3995.9400608990886;4017.9397309040387;4029.1538776857187;4041.2321658884716;694.0768777525878;703.3423748794893;714.0182249407065;715.2775696546526;726.8655298769745;740.9814754631177;748.6631016042759;756.8902509136144;765.9578723170167;782.0631003003397;10042.358716964953;10403.687889363331;10823;11024.228304018641;11500.827487587692;726.9491135620456;755.720383458124;756.2097607999654;791.5646394483011;793.1592511937365;5032.3206367139965;5078.619103567247;5099.133147364957;5181.274621552986;5259.184826351921;127103;134676;134813;138617.3069134648;138771;113543;114948;115280;116854;117961.41019294849;62626;95464;96608;104981.95018049875;106661.46669266604;9500.320084959138;9902.257330700082;10273.486325683536;10385.909479504544;10465.68602941913;41666.79166604148;42377.36433953432;47799.52200477951;48163.759181203866;49544.75227623839;76598.23401766134;79202.6039869797;79418;80614;84624;13207.471701132232;14386.856131438552;14474.203918784568;14540.636484087983;14955.700885982436;14778.48275310359;15588.92205538965;16202.108884011492;16874.74687879658;17123.82876171272;15148.772768408704;15151.621209469851;16102.114383709008;16993.150342483073;17045.659086818443;14020.859791402363;15146.318415671101;15256.542303730463;16651.50045498637;16698.74951875747;14669.559913202622;15735.134567598892;15911.602209944844;16413.343466261216;16428.096454695107;11709.824352634887;11812.350320732443;11926.165168438472;12164.695882603006;12554;11639.825402618799;11904.821427678766;11969.64091077269;11976.880231197576;12134.817977730165 10:37:37 INFO - PROCESS | 3398 | __end_tp_report 10:37:37 INFO - PROCESS | 3398 | __start_cc_report 10:37:37 INFO - PROCESS | 3398 | _x_x_mozilla_cycle_collect,915 10:37:37 INFO - PROCESS | 3398 | __end_cc_report 10:37:37 INFO - PROCESS | 3398 | __startTimestamp1476207457425__endTimestamp 10:37:37 INFO - PROCESS | 3398 | 10:37:37 INFO - PROCESS | 3398 | ------- Summary: start ------- 10:37:37 INFO - PROCESS | 3398 | Number of tests: 6 10:37:37 INFO - PROCESS | 3398 | 10:37:37 INFO - PROCESS | 3398 | [#0] dojo.html Cycles:165 Average:5542.95 Median:5785.71 stddev:632.99 (10.9%) stddev-sans-first:606.18 10:37:37 INFO - PROCESS | 3398 | Values: 3138.9 3154.3 3162.6 3194.5 3212.4 5465.4 5566.5 5584.1 5590.5 5618.1 5579.6 5605.8 5642.0 5660.0 5686.0 5096.6 5113.9 5150.9 5177.4 5187.5 4965.9 5037.3 5179.7 5186.5 5189.6 4973.3 4998.3 5024.2 5060.1 5094.6 5098.3 5137.9 5218.7 5219.5 5255.3 4865.7 5102.7 5120.9 5160.9 5180.1 5421.9 5435.4 5452.0 5452.1 5471.1 5860.7 5911.3 5966.8 5971.3 5972.6 5866.8 5945.8 5998.9 6006.0 6009.7 5543.1 5648.6 5664.5 5696.5 5704.0 6158.1 6196.3 6209.1 6209.7 6210.3 5734.1 5807.4 5864.8 5954.9 5966.8 5984.2 6003.8 6005.3 6025.2 6040.5 5977.3 5983.3 5988.8 5994.1 5995.8 5706.1 5810.7 5813.0 5995.5 5999.6 4528.4 4530.9 4532.1 4533.9 4539.7 4006.2 4044.4 4063.7 4068.6 4086.2 5735.4 5749.9 5750.7 5754.9 5757.9 6006.8 6018.2 6020.5 6023.8 6026.8 5538.4 5543.8 5573.9 5584.9 5701.1 5113.1 5122.5 5123.7 5125.4 5148.4 5997.4 6079.7 6098.5 6106.2 6107.4 5804.6 5859.9 5880.9 5895.2 5902.2 6133.2 6137.1 6146.8 6156.0 6156.1 5836.6 5841.3 5845.1 5848.4 5881.8 5742.2 5842.6 5867.2 5887.7 5937.3 5756.1 5814.2 5832.9 5840.7 5862.3 5796.4 5824.5 5845.2 5845.5 5847.9 5689.1 5760.3 5772.1 5806.2 5860.5 5938.9 5940.5 5943.5 5957.5 5969.2 5785.7 5804.5 5808.1 5809.8 5839.1 10:37:37 INFO - PROCESS | 3398 | 10:37:37 INFO - PROCESS | 3398 | [#1] ext.html Cycles:180 Average:67924.37 Median:225778.50 stddev:135479.90 (60.0%) stddev-sans-first:135765.64 10:37:37 INFO - PROCESS | 3398 | Values: 593.3 624.5 643.3 649.9 650.8 37910.0 46825.3 47975.5 48029.8 52469.2 49701.0 50212.5 50336.0 50354.5 50438.2 8310.3 9260.7 9417.3 9423.3 9427.4 10972.2 11275.8 11278.5 11565.4 11578.9 11306.5 11402.3 11406.9 11413.3 11415.3 11270.0 11343.3 11343.8 11346.0 11352.5 11064.4 11108.5 11145.3 11149.1 11150.1 46267.5 46588.5 46628.5 46655.3 46675.8 3756.8 3769.0 3829.3 3875.3 3967.9 35899.5 35963.3 36323.3 37320.4 37344.8 4743.8 4819.1 4855.2 4902.3 5034.6 410508.0 410842.0 413438.0 414462.0 417247.0 70070.6 70467.3 70656.6 70718.9 70722.6 41049.0 42075.8 42091.8 44418.3 44473.0 20312.7 20512.0 20517.5 20530.7 20534.3 13888.5 14643.5 15565.7 15673.3 15692.5 20507.7 21486.5 21536.6 21541.7 21568.8 7296.2 7513.6 7650.5 7678.9 7705.0 1035.9 1126.3 1156.5 1220.3 1227.6 36051.5 36100.8 36180.0 36416.0 36473.0 1107.3 1160.4 1168.5 1180.9 1195.0 18679.0 18883.6 18903.2 18904.5 19022.8 625336.0 629368.0 631045.0 635674.0 636725.0 66700.7 66965.0 67269.0 67281.0 67387.7 1347.4 1442.4 1477.1 1494.4 1508.6 33571.2 36196.5 36474.0 36670.6 36913.8 57145.0 58112.7 58199.2 58199.7 58226.4 451024.0 454484.0 454697.0 454856.0 489195.0 31552.5 31586.7 31669.5 33638.8 33900.7 54102.0 54533.5 54734.5 54806.5 56104.7 44511.2 46508.0 47453.5 47711.3 49038.0 45124.2 45580.3 46749.8 46827.3 48539.8 45671.0 45950.5 46122.3 46172.3 46896.5 38959.4 40398.2 40628.6 40657.4 41473.2 30298.1 30352.0 30535.4 30578.4 30882.2 10:37:37 INFO - PROCESS | 3398 | 10:37:37 INFO - PROCESS | 3398 | [#2] jquery.html Cycles:180 Average:29734.25 Median:2319.44 stddev:83023.87 (3579.5%) stddev-sans-first:83227.60 10:37:37 INFO - PROCESS | 3398 | Values: 419.4 421.8 428.1 430.9 443.5 18582.5 20258.7 20699.3 21428.5 21599.1 20051.5 20398.2 20801.0 20947.5 21135.9 5814.4 6018.7 6021.4 6035.1 6037.1 5538.7 5771.3 5797.6 5884.9 5902.2 5880.4 5885.2 5890.2 6331.1 6386.1 5853.5 6112.5 6207.7 6349.1 6350.1 5877.7 5984.3 5990.5 6000.5 6001.9 13304.7 13715.7 14115.5 14219.1 14270.1 2405.8 2441.8 2459.1 2468.6 2487.6 10471.0 10959.9 14006.9 15047.7 15208.5 2023.2 2068.9 2194.7 2210.8 2233.1 67432.7 75701.0 84751.6 86718.0 88604.0 11768.7 11858.3 11965.0 11971.4 12227.5 17155.3 17185.9 17236.9 17238.1 17253.1 9076.8 9230.5 9292.5 9302.3 9354.3 8962.7 9011.8 9019.5 9192.7 9221.0 5028.4 5077.7 5144.3 5153.2 5268.3 2044.2 2066.8 2084.4 2088.9 2100.2 962.9 1040.3 1071.8 1089.8 1128.3 20190.7 20349.4 20373.2 20656.9 20928.8 1005.7 1032.3 1050.8 1081.0 1085.1 10646.6 11593.7 11654.5 11655.2 11697.5 488779.0 498718.0 499134.0 505108.0 528007.0 75400.6 92195.0 94671.0 97241.5 97564.0 989.6 996.2 1006.8 1023.9 1025.9 17963.0 18035.0 18505.4 18757.8 18970.1 40282.8 40649.6 43163.8 43522.6 45831.8 55634.7 61664.4 62621.0 63320.0 63900.4 8789.4 8843.2 8907.5 8970.3 9010.5 13591.6 13948.9 14076.5 14274.5 14607.2 11831.2 12337.7 12566.6 12578.0 12798.3 12107.6 12233.6 12321.4 12356.3 12566.7 6370.6 11415.0 12079.0 12269.4 12487.0 8007.4 8913.2 9054.4 9057.2 9370.1 10058.9 11086.9 11265.3 11794.7 12172.8 10:37:37 INFO - PROCESS | 3398 | 10:37:37 INFO - PROCESS | 3398 | [#3] mootools.html Cycles:180 Average:6153.96 Median:473.61 stddev:3522.91 (743.8%) stddev-sans-first:3515.98 10:37:37 INFO - PROCESS | 3398 | Values: 1574.3 1603.3 1642.8 1707.7 1793.8 8818.5 9487.6 9543.1 9545.0 9555.4 8154.6 8726.4 9051.2 9074.7 9310.3 2461.6 2501.0 2509.0 2511.1 2526.3 2437.9 2526.9 2528.4 2533.7 2533.9 2455.5 2473.8 2495.1 2495.7 2496.1 2399.8 2404.5 2411.6 2441.8 2469.5 2429.6 2430.1 2436.5 2442.6 2465.2 9400.3 9418.6 9465.1 9474.4 9493.9 5525.1 5657.5 5659.6 5664.3 5665.8 3607.2 3679.1 3744.4 3750.5 3760.8 1144.5 1200.0 1224.3 1225.3 1232.1 13214.5 13237.9 13246.3 13258.7 13266.0 4836.0 4891.7 4982.8 5037.5 5037.6 11041.6 11056.4 11073.0 11078.8 11079.1 5669.1 5692.4 5728.7 5729.4 5750.6 4512.5 4601.2 5239.5 5246.9 5259.9 4196.9 4265.0 4280.3 4291.7 4346.6 1300.3 1304.5 1315.4 1338.0 1354.0 919.9 979.6 1031.0 1036.5 1046.3 6144.4 6270.8 6388.5 6420.6 6491.4 470.5 476.8 481.2 485.7 507.5 4235.2 4388.7 4401.8 4435.4 4528.2 11241.9 11761.1 11773.1 11775.9 11776.9 11416.4 11433.8 11592.1 11619.5 11622.9 8734.3 8746.2 8770.7 8815.4 8828.3 6339.3 6363.9 6429.1 6467.7 6487.5 10538.8 10757.1 10780.1 10878.4 10887.5 10760.5 10773.3 10886.9 10924.0 10930.8 7488.3 7995.7 8159.7 8337.9 8398.9 9004.2 9106.5 9114.5 9118.1 9121.4 7947.7 8002.2 8081.6 8165.8 8175.1 7732.5 7863.1 7871.8 7951.4 7996.2 6882.6 7082.8 7186.5 7190.5 7192.6 5620.8 5676.4 5740.1 5743.7 5762.1 5447.8 5470.1 5473.7 5572.2 5625.5 10:37:37 INFO - PROCESS | 3398 | 10:37:37 INFO - PROCESS | 3398 | [#4] prototype.html Cycles:180 Average:4796.73 Median:5447.12 stddev:1400.71 (25.7%) stddev-sans-first:1364.39 10:37:37 INFO - PROCESS | 3398 | Values: 354.7 355.5 356.6 357.7 366.2 5466.5 5468.9 5480.6 5493.5 5536.6 5384.1 5434.4 5459.8 5464.0 5465.7 3774.6 4119.4 4228.3 4268.3 4337.8 4315.6 4321.3 4325.7 4335.3 4350.8 4318.7 4323.3 4329.4 4332.7 4334.6 4079.3 4250.7 4297.8 4322.5 4334.5 3793.5 3794.6 3798.6 3799.6 3807.5 5283.3 5294.5 5314.2 5325.1 5346.9 4766.7 4830.5 4845.8 4849.2 4896.0 5673.2 5673.7 5685.6 5686.7 5696.8 4157.2 4166.1 4168.4 4174.0 4177.2 5657.9 5834.4 5835.7 5840.8 5876.5 4199.4 4220.4 4227.6 4232.8 4234.7 4267.6 4285.4 4289.1 4294.8 4297.1 4685.6 4816.1 4834.9 4851.4 4855.6 5606.5 5633.2 5636.9 5644.8 5681.6 1475.4 1488.8 1494.2 1494.8 1495.9 915.6 958.7 959.0 959.6 969.4 5012.9 5013.2 5016.2 5025.6 5031.0 5164.3 5182.1 5205.6 5212.5 5219.3 4954.9 4975.7 5017.9 5261.3 5617.3 3129.7 3137.3 3138.5 3158.6 3160.5 6104.5 6108.6 6108.7 6119.8 6137.9 5757.5 5758.3 6020.3 6037.9 6050.8 5899.7 6089.3 6090.6 6120.8 6123.4 5923.2 5926.2 5930.9 5933.4 5944.4 5615.4 5840.9 5857.4 5879.1 5884.6 6155.8 6155.9 6158.4 6166.8 6176.8 4054.4 4257.3 4259.3 4263.6 4263.8 5882.2 5889.7 5890.4 5895.4 5895.6 5756.6 5763.3 5764.1 5774.7 5792.3 5589.6 5769.1 5769.6 5776.3 5789.7 5759.7 5771.0 5779.8 5787.1 5791.2 5586.5 5733.6 5801.2 5809.5 5821.2 5613.8 5628.8 5633.8 5639.7 5643.5 10:37:37 INFO - PROCESS | 3398 | 10:37:37 INFO - PROCESS | 3398 | [#5] yui.html Cycles:180 Average:23197.78 Median:3930.93 stddev:34357.47 (874.0%) stddev-sans-first:34412.58 10:37:37 INFO - PROCESS | 3398 | Values: 769.6 792.2 799.2 834.3 918.7 16927.9 17410.5 17756.6 17925.4 18068.5 16131.5 16291.8 16315.8 16316.8 16335.3 748.5 878.3 885.8 897.5 900.4 895.9 899.8 904.5 904.5 905.1 949.6 953.3 954.1 954.3 955.2 948.1 949.8 950.2 950.4 950.5 696.6 699.5 700.4 701.3 701.8 14280.4 14312.4 14317.6 14335.9 14350.6 14544.0 14666.6 15123.8 15333.4 16470.5 14218.9 14413.3 14424.6 15014.5 15016.8 4834.4 4837.4 4872.9 4997.2 5035.1 92901.0 93033.5 93359.5 94193.0 94286.0 12275.7 12444.3 12496.6 12602.7 12796.7 21893.0 22671.7 22820.3 22832.4 22866.7 9260.5 9313.7 9370.6 9474.2 9705.3 4747.7 4944.9 5027.8 5066.4 5075.8 3865.9 3995.9 4017.9 4029.2 4041.2 694.1 703.3 714.0 715.3 726.9 741.0 748.7 756.9 766.0 782.1 10042.4 10403.7 10823.0 11024.2 11500.8 726.9 755.7 756.2 791.6 793.2 5032.3 5078.6 5099.1 5181.3 5259.2 127103.0 134676.0 134813.0 138617.3 138771.0 113543.0 114948.0 115280.0 116854.0 117961.4 62626.0 95464.0 96608.0 104982.0 106661.5 9500.3 9902.3 10273.5 10385.9 10465.7 41666.8 42377.4 47799.5 48163.8 49544.8 76598.2 79202.6 79418.0 80614.0 84624.0 13207.5 14386.9 14474.2 14540.6 14955.7 14778.5 15588.9 16202.1 16874.7 17123.8 15148.8 15151.6 16102.1 16993.2 17045.7 14020.9 15146.3 15256.5 16651.5 16698.7 14669.6 15735.1 15911.6 16413.3 16428.1 11709.8 11812.4 11926.2 12164.7 12554.0 11639.8 11904.8 11969.6 11976.9 12134.8 10:37:37 INFO - PROCESS | 3398 | -------- Summary: end -------- 10:37:37 INFO - PROCESS | 3398 | 10:37:38 INFO - TEST-INFO | 3398: exit 0 10:37:38 INFO - TEST-OK | dromaeo_css | took 1113695ms 10:37:38 INFO - TEST-START | kraken 10:37:38 INFO - operating with platform_type : linux_ 10:37:38 INFO - Initialising browser for kraken test... 10:37:38 INFO - TEST-INFO | started process 3587 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpivetKi/profile http://localhost:42078/getInfo.html) 10:37:42 INFO - PROCESS | 3587 | __metrics Screen width/height:1600/1200 10:37:42 INFO - PROCESS | 3587 | colorDepth:24 10:37:42 INFO - PROCESS | 3587 | Browser inner width/height: 1024/697 10:37:42 INFO - PROCESS | 3587 | __metrics 10:37:43 INFO - PROCESS | 3587 | 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" 10:37:43 INFO - PROCESS | 3587 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:37:44 INFO - TEST-INFO | 3587: exit 0 10:37:44 INFO - Browser initialized. 10:37:44 INFO - Running cycle 1/1 for kraken test... 10:37:44 INFO - TEST-INFO | started process 3676 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpivetKi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:37:56 INFO - PROCESS | 3676 | RSS: Main: 143794176 10:37:56 INFO - PROCESS | 3676 | 10:37:58 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:42078/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42078/tests/kraken/driver.html?testName=audio-beat-detection) 10:37:59 INFO - PROCESS | 3676 | RSS: Main: 199438336 10:37:59 INFO - PROCESS | 3676 | 10:38:01 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:42078/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42078/tests/kraken/driver.html?testName=audio-dft) 10:38:02 INFO - PROCESS | 3676 | RSS: Main: 184762368 10:38:02 INFO - PROCESS | 3676 | 10:38:05 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:42078/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42078/tests/kraken/driver.html?testName=audio-fft) 10:38:05 INFO - PROCESS | 3676 | RSS: Main: 275714048 10:38:05 INFO - PROCESS | 3676 | 10:38:07 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:42078/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42078/tests/kraken/driver.html?testName=audio-oscillator) 10:38:08 INFO - PROCESS | 3676 | RSS: Main: 180862976 10:38:08 INFO - PROCESS | 3676 | 10:38:10 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:42078/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42078/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:38:11 INFO - PROCESS | 3676 | RSS: Main: 179535872 10:38:11 INFO - PROCESS | 3676 | 10:38:14 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:42078/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42078/tests/kraken/driver.html?testName=imaging-darkroom) 10:38:14 INFO - PROCESS | 3676 | RSS: Main: 215605248 10:38:14 INFO - PROCESS | 3676 | 10:38:18 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:42078/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42078/tests/kraken/driver.html?testName=imaging-desaturate) 10:38:18 INFO - PROCESS | 3676 | RSS: Main: 214511616 10:38:18 INFO - PROCESS | 3676 | 10:38:21 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:42078/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42078/tests/kraken/driver.html?testName=json-parse-financial) 10:38:22 INFO - PROCESS | 3676 | RSS: Main: 214028288 10:38:22 INFO - PROCESS | 3676 | 10:38:23 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:42078/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42078/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:38:24 INFO - PROCESS | 3676 | RSS: Main: 175812608 10:38:24 INFO - PROCESS | 3676 | 10:38:26 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:42078/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42078/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:38:26 INFO - PROCESS | 3676 | RSS: Main: 182558720 10:38:26 INFO - PROCESS | 3676 | 10:38:29 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:42078/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42078/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:38:30 INFO - PROCESS | 3676 | RSS: Main: 189358080 10:38:30 INFO - PROCESS | 3676 | 10:38:32 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:42078/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42078/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:38:33 INFO - PROCESS | 3676 | RSS: Main: 197754880 10:38:33 INFO - PROCESS | 3676 | 10:38:35 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:42078/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42078/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:38:36 INFO - PROCESS | 3676 | RSS: Main: 194732032 10:38:36 INFO - PROCESS | 3676 | 10:38:38 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:42078/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42078/tests/kraken/driver.html?testName=ai-astar) 10:38:38 INFO - PROCESS | 3676 | RSS: Main: 244486144 10:38:38 INFO - PROCESS | 3676 | 10:38:38 INFO - PROCESS | 3676 | __start_tp_report 10:38:38 INFO - PROCESS | 3676 | _x_x_mozilla_page_load 10:38:38 INFO - PROCESS | 3676 | _x_x_mozilla_page_load_details 10:38:38 INFO - PROCESS | 3676 | |i|pagename|runs| 10:38:38 INFO - PROCESS | 3676 | |0;ai-astar;112;111;121;111;111;111;110;110;111;111 10:38:38 INFO - PROCESS | 3676 | |1;audio-beat-detection;146;120;140;176;116;146;139;116;161;115 10:38:38 INFO - PROCESS | 3676 | |2;audio-dft;161;159;161;159;159;161;161;159;160;158 10:38:38 INFO - PROCESS | 3676 | |3;audio-fft;74;75;75;175;75;77;73;74;170;75 10:38:38 INFO - PROCESS | 3676 | |4;audio-oscillator;82;83;150;84;84;83;147;83;83;139 10:38:38 INFO - PROCESS | 3676 | |5;imaging-gaussian-blur;116;116;118;116;116;116;117;117;117;117 10:38:38 INFO - PROCESS | 3676 | |6;imaging-darkroom;146;145;146;146;146;146;145;146;146;146 10:38:38 INFO - PROCESS | 3676 | |7;imaging-desaturate;95;95;94;95;95;94;95;94;95;95 10:38:38 INFO - PROCESS | 3676 | |8;json-parse-financial;52;50;50;50;50;51;52;49;50;50 10:38:38 INFO - PROCESS | 3676 | |9;json-stringify-tinderbox;61;61;59;61;61;60;60;60;61;61 10:38:38 INFO - PROCESS | 3676 | |10;stanford-crypto-aes;73;72;72;75;77;78;72;72;73;77 10:38:38 INFO - PROCESS | 3676 | |11;stanford-crypto-ccm;122;120;122;121;120;127;118;123;166;125 10:38:38 INFO - PROCESS | 3676 | |12;stanford-crypto-pbkdf2;152;154;151;155;148;149;151;151;160;151 10:38:38 INFO - PROCESS | 3676 | |13;stanford-crypto-sha256-iterative;68;64;62;61;62;60;67;66;64;60 10:38:38 INFO - PROCESS | 3676 | __end_tp_report 10:38:38 INFO - PROCESS | 3676 | __start_cc_report 10:38:38 INFO - PROCESS | 3676 | _x_x_mozilla_cycle_collect,1720 10:38:38 INFO - PROCESS | 3676 | __end_cc_report 10:38:38 INFO - PROCESS | 3676 | __startTimestamp1476207518430__endTimestamp 10:38:38 INFO - PROCESS | 3676 | 10:38:38 INFO - PROCESS | 3676 | ------- Summary: start ------- 10:38:38 INFO - PROCESS | 3676 | Number of tests: 14 10:38:38 INFO - PROCESS | 3676 | 10:38:38 INFO - PROCESS | 3676 | [#0] ai-astar Cycles:10 Average:111.90 Median:111.00 stddev:3.25 (2.9%) stddev-sans-first:3.44 10:38:38 INFO - PROCESS | 3676 | Values: 112.0 111.0 121.0 111.0 111.0 111.0 110.0 110.0 111.0 111.0 10:38:38 INFO - PROCESS | 3676 | 10:38:38 INFO - PROCESS | 3676 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:143.00 stddev:20.88 (14.6%) stddev-sans-first:21.92 10:38:38 INFO - PROCESS | 3676 | Values: 146.0 120.0 140.0 176.0 116.0 146.0 139.0 116.0 161.0 115.0 10:38:38 INFO - PROCESS | 3676 | 10:38:38 INFO - PROCESS | 3676 | [#2] audio-dft Cycles:10 Average:159.80 Median:160.50 stddev:1.14 (0.7%) stddev-sans-first:1.12 10:38:38 INFO - PROCESS | 3676 | Values: 161.0 159.0 161.0 159.0 159.0 161.0 161.0 159.0 160.0 158.0 10:38:38 INFO - PROCESS | 3676 | 10:38:38 INFO - PROCESS | 3676 | [#3] audio-fft Cycles:10 Average:94.30 Median:75.00 stddev:41.24 (55.0%) stddev-sans-first:43.09 10:38:38 INFO - PROCESS | 3676 | Values: 74.0 75.0 75.0 175.0 75.0 77.0 73.0 74.0 170.0 75.0 10:38:38 INFO - PROCESS | 3676 | 10:38:38 INFO - PROCESS | 3676 | [#4] audio-oscillator Cycles:10 Average:101.80 Median:83.00 stddev:30.17 (36.3%) stddev-sans-first:31.13 10:38:38 INFO - PROCESS | 3676 | Values: 82.0 83.0 150.0 84.0 84.0 83.0 147.0 83.0 83.0 139.0 10:38:38 INFO - PROCESS | 3676 | 10:38:38 INFO - PROCESS | 3676 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 10:38:38 INFO - PROCESS | 3676 | Values: 116.0 116.0 118.0 116.0 116.0 116.0 117.0 117.0 117.0 117.0 10:38:38 INFO - PROCESS | 3676 | 10:38:38 INFO - PROCESS | 3676 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 10:38:38 INFO - PROCESS | 3676 | Values: 146.0 145.0 146.0 146.0 146.0 146.0 145.0 146.0 146.0 146.0 10:38:38 INFO - PROCESS | 3676 | 10:38:38 INFO - PROCESS | 3676 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 10:38:38 INFO - PROCESS | 3676 | Values: 95.0 95.0 94.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 10:38:38 INFO - PROCESS | 3676 | 10:38:38 INFO - PROCESS | 3676 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:50.00 stddev:0.97 (1.9%) stddev-sans-first:0.83 10:38:38 INFO - PROCESS | 3676 | Values: 52.0 50.0 50.0 50.0 50.0 51.0 52.0 49.0 50.0 50.0 10:38:38 INFO - PROCESS | 3676 | 10:38:38 INFO - PROCESS | 3676 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:61.00 stddev:0.71 (1.2%) stddev-sans-first:0.73 10:38:38 INFO - PROCESS | 3676 | Values: 61.0 61.0 59.0 61.0 61.0 60.0 60.0 60.0 61.0 61.0 10:38:38 INFO - PROCESS | 3676 | 10:38:38 INFO - PROCESS | 3676 | [#10] stanford-crypto-aes Cycles:10 Average:74.10 Median:74.00 stddev:2.42 (3.3%) stddev-sans-first:2.54 10:38:38 INFO - PROCESS | 3676 | Values: 73.0 72.0 72.0 75.0 77.0 78.0 72.0 72.0 73.0 77.0 10:38:38 INFO - PROCESS | 3676 | 10:38:38 INFO - PROCESS | 3676 | [#11] stanford-crypto-ccm Cycles:10 Average:126.40 Median:122.50 stddev:14.15 (11.6%) stddev-sans-first:14.92 10:38:38 INFO - PROCESS | 3676 | Values: 122.0 120.0 122.0 121.0 120.0 127.0 118.0 123.0 166.0 125.0 10:38:38 INFO - PROCESS | 3676 | 10:38:38 INFO - PROCESS | 3676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.20 Median:151.50 stddev:3.43 (2.3%) stddev-sans-first:3.63 10:38:38 INFO - PROCESS | 3676 | Values: 152.0 154.0 151.0 155.0 148.0 149.0 151.0 151.0 160.0 151.0 10:38:38 INFO - PROCESS | 3676 | 10:38:38 INFO - PROCESS | 3676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:64.00 stddev:2.88 (4.5%) stddev-sans-first:2.52 10:38:38 INFO - PROCESS | 3676 | Values: 68.0 64.0 62.0 61.0 62.0 60.0 67.0 66.0 64.0 60.0 10:38:38 INFO - PROCESS | 3676 | -------- Summary: end -------- 10:38:38 INFO - PROCESS | 3676 | 10:38:39 INFO - TEST-INFO | 3676: exit 0 10:38:39 INFO - TEST-OK | kraken | took 61173ms 10:38:39 INFO - SUITE-END | took 1174s 10:38:39 INFO - Completed test suite (00:19:35) 10:38:39 INFO - javascript benchmark 10:38:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3138.8744450221993, 3154.3060526684135, 3162.5888634477524, 3194.536792165136, 3212.3575284943017, 5465.371482279534, 5566.471185237401, 5584.134459158837, 5590.524805391537, 5618.073017952033, 5579.553635709143, 5605.803796867111, 5642.0, 5660.037793575092, 5685.971570142154, 5096.643234973553, 5113.872153196163, 5150.8712282193055, 5177.352830896138, 5187.507186817246, 4965.851024469272, 5037.345145131146, 5179.689218646893, 5186.507281808223, 5189.584833213334, 4973.254011898226, 4998.300237966688, 5024.171011783052, 5060.06388818068, 5094.61790365724, 5098.311727916739, 5137.871553211162, 5218.712970786605, 5219.530242278213, 5255.264263003182, 4865.70805751656, 5102.668326558799, 5120.92460583278, 5160.896782064376, 5180.093483640348, 5421.918671219895, 5435.374931882805, 5452.04589196889, 5452.100403433429, 5471.06991811393, 5860.677662728548, 5911.29064512257, 5966.820995370146, 5971.343152253248, 5972.64164150148, 5866.76532938682, 5945.791897283618, 5998.880022399571, 6006.00900851359, 6009.699515024231, 5543.085390910495, 5648.576356773299, 5664.54683625309, 5696.544276457874, 5704.030314846486, 6158.107074474176, 6196.287426945869, 6209.130721698965, 6209.6895155242055, 6210.285817130997, 5734.111212761945, 5807.419258074243, 5864.765409383662, 5954.8809023819285, 5966.791162309299, 5984.222051133324, 6003.849903752441, 6005.3394126646035, 6025.246844144482, 6040.486558642477, 5977.3126090500145, 5983.311919129269, 5988.760449581968, 5994.130851026577, 5995.790147344822, 5706.115552089433, 5810.738516766755, 5812.9709351452975, 5995.52035837132, 5999.550033747487, 4528.388667529841, 4530.932036019462, 4532.093581283756, 4533.909321813545, 4539.682222244412, 4006.1787333596544, 4044.352903535479, 4063.7358571692744, 4068.572799856032, 4086.203190377848, 5735.369109398046, 5749.88500229993, 5750.654960702372, 5754.88490230193, 5757.9424205757405, 6006.789762358297, 6018.247719035121, 6020.548458865603, 6023.819285421444, 6026.849328766816, 5538.446155384429, 5543.805966791224, 5573.944260557342, 5584.91622625661, 5701.116326969326, 5113.130767769489, 5122.513361230677, 5123.718195499208, 5125.410577783528, 5148.4079330876675, 5997.370276121032, 6079.6960151992225, 6098.542609304409, 6106.175666284995, 6107.389261073857, 5804.564657650609, 5859.882802344015, 5880.941190588211, 5895.174675545341, 5902.2327097477055, 6133.202683651275, 6137.101203255209, 6146.784862529968, 6156.015037593964, 6156.1381406604005, 5836.591438599427, 5841.269841269841, 5845.1232315153065, 5848.385919478564, 5881.7647294109465, 5742.196092546963, 5842.620229685057, 5867.178594996619, 5887.7056147193325, 5937.3468918418075, 5756.136579513107, 5814.186013958134, 5832.854178645568, 5840.678762668094, 5862.267216597926, 5796.362400136066, 5824.534037276923, 5845.152452894392, 5845.503132233808, 5847.883042339045, 5689.118186681071, 5760.251167348218, 5772.076467765138, 5806.187133801355, 5860.531157507475, 5938.881222375442, 5940.495057920125, 5943.4948029416255, 5957.523398128053, 5969.224000879858, 5785.710714464176, 5804.535637148933, 5808.07070868659, 5809.767609295581, 5839.065749480063], "value": 5499.394416903945, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.273240280656, 624.4879199056774, 643.3437893348781, 649.8635286589816, 650.7917466410747, 37910.0, 46825.29762053572, 47975.52024479754, 48029.759851200695, 52469.21296180551, 49701.0, 50212.49787502124, 50336.0, 50354.49645503544, 50438.243426348636, 8310.335173186131, 9260.722178334661, 9417.340786144972, 9423.34036617437, 9427.387219830724, 10972.23194376394, 11275.774484510304, 11278.548858045668, 11565.363904985223, 11578.884211157907, 11306.491207895624, 11402.258853174517, 11406.885931140707, 11413.25813822103, 11415.25800822948, 11270.042046426022, 11343.319400835977, 11343.773124537545, 11346.0, 11352.467416898695, 11064.446777661085, 11108.500117494732, 11145.331280123219, 11149.052330551913, 11150.107991360672, 46267.53732462666, 46588.53411465876, 46628.53371466276, 46655.30017049747, 46675.766621167015, 3756.7745935243975, 3769.0, 3829.253295607358, 3875.3411919973673, 3967.9008024799323, 35899.4615080774, 35963.28073438543, 36323.27353452916, 37320.440193397124, 37344.81327593345, 4743.810247590127, 4819.08437396897, 4855.24743664729, 4902.313676085351, 5034.647574669774, 410508.0, 410842.0, 413438.0, 414462.0, 417247.0, 70070.64964675144, 70467.29532704607, 70656.64671676609, 70718.87843364707, 70722.64638676774, 41049.0, 42075.78962105231, 42091.78954105271, 44418.333724994154, 44473.0, 20312.69530957037, 20512.0, 20517.487062823548, 20530.69203961942, 20534.281300154424, 13888.513902013381, 14643.487477938434, 15565.688686226438, 15673.294701738449, 15692.529224123295, 20507.69238461424, 21486.462838429165, 21536.569268614854, 21541.67687484689, 21568.784312156677, 7296.233895441009, 7513.586752728543, 7650.540967541965, 7678.884816727754, 7704.9614751927, 1035.8705161854768, 1126.2510430563584, 1156.5373850459714, 1220.3166226912956, 1227.5580790915262, 36051.4592281116, 36100.81949590235, 36180.0, 36416.0, 36473.0, 1107.2526044919807, 1160.431388619587, 1168.4683469021575, 1180.9173357864868, 1194.9820752688718, 18679.0, 18883.62232755337, 18903.243870245034, 18904.52738681544, 19022.80977190238, 625336.0, 629368.0, 631045.0, 635674.0, 636725.0, 66700.66649666721, 66965.0, 67269.0, 67281.0, 67387.66306168537, 1347.4273433790795, 1442.4374493947369, 1477.0842077911764, 1494.3873012065, 1508.630385555546, 33571.16072098119, 36196.45705314475, 36474.0, 36670.633293666724, 36913.815430922674, 57145.0, 58112.70943645255, 58199.21705089325, 58199.70900145642, 58226.417735823794, 451024.0, 454484.0, 454697.0, 454856.0, 489195.0, 31552.526712099796, 31586.684133158375, 31669.524957125202, 33638.83180584081, 33900.66099339074, 54102.0, 54533.454665454425, 54734.45265547294, 54806.45193548014, 56104.71947640236, 44511.21955121759, 46508.0, 47453.52546474629, 47711.28433073576, 49038.0, 45124.17230935163, 45580.31629525493, 46749.76625116989, 46827.29759053685, 48539.75730121327, 45671.0, 45950.54049459463, 46122.308165378214, 46172.30741538947, 46896.531034689215, 38959.415608765325, 40398.1920361597, 40628.59371406248, 40657.39013914735, 41473.1705365897, 30298.098380953314, 30352.0, 30535.389292213586, 30578.388432231677, 30882.227944301572], "value": 22567.492674408448, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.4379531427886, 421.7848897062497, 428.09457996337744, 430.91597138557984, 443.4611946485022, 18582.535436614075, 20258.69611955822, 20699.275525356614, 21428.4642883928, 21599.136034558636, 20051.498712532193, 20398.184072637076, 20801.0, 20947.476313092182, 21135.894320528376, 5814.418558144183, 6018.6990650467305, 6021.42796434338, 6035.064564992411, 6037.124616930542, 5538.695371754552, 5771.3074431068335, 5797.5651826113, 5884.852878678024, 5902.173695682608, 5880.353161152269, 5885.234919460464, 5890.204822348992, 6331.14529538511, 6386.0740192672265, 5853.473187413153, 6112.541559383064, 6207.658578778165, 6349.079383489391, 6350.142730731362, 5877.735501902382, 5984.281886173687, 5990.460858522711, 6000.459958603747, 6001.879962400772, 13304.733905321937, 13715.657108572366, 14115.505957291549, 14219.075760075666, 14270.143791372448, 2405.7594240575977, 2441.755824417544, 2459.114718701266, 2468.642046903207, 2487.589547724623, 10471.005254500811, 10959.890401095967, 14006.92996535021, 15047.699046019128, 15208.543743687707, 2023.1603884387962, 2068.927587534429, 2194.6817711431754, 2210.767869373709, 2233.117918422214, 67432.662836686, 75701.0, 84751.57624211839, 86718.0, 88604.0, 11768.70578235551, 11858.288502689693, 11965.0, 11971.401429928468, 12227.510899564095, 17155.313787448362, 17185.91407042982, 17236.913815430842, 17238.138093095295, 17253.137343132792, 9076.81846363069, 9230.492322922304, 9292.535373231447, 9302.348835581579, 9354.345195836362, 8962.73111806647, 9011.774705632411, 9019.458832470073, 9192.724218273463, 9221.0, 5028.446870844205, 5077.654421578285, 5144.331236939174, 5153.175491921274, 5268.341457317835, 2044.1516770540059, 2066.7519897612083, 2084.4059443058654, 2088.853780235369, 2100.191426300855, 962.8892677342055, 1040.3393844908387, 1071.7534966957644, 1089.7827891876823, 1128.3230061962756, 20190.697139542917, 20349.389518314474, 20373.18507259693, 20656.863872487156, 20928.79071209299, 1005.6882366466418, 1032.3238278927315, 1050.8003479338902, 1080.9840544879855, 1085.1318944844093, 10646.574137034431, 11593.6521904343, 11654.475548600334, 11655.242409243372, 11697.473613687405, 488779.0, 498718.0, 499134.0, 505108.0, 528007.0, 75400.62299688466, 92195.0, 94671.0, 97241.513792432, 97564.0, 989.5547003848297, 996.2179688944144, 1006.8087063458066, 1023.8976102389702, 1025.9230557708051, 17963.0, 18035.0, 18505.415555434884, 18757.812421875606, 18970.051497424796, 40282.79858600688, 40649.59350406458, 43163.78418108015, 43522.564774353115, 45831.77084114558, 55634.72182639061, 61664.38335616766, 62621.0, 63320.0, 63900.36099638944, 8789.428687135316, 8843.159899809507, 8907.51008694528, 8970.282377409661, 9010.459372437675, 13591.592252232449, 13948.860511394756, 14076.507322243673, 14274.500392486214, 14607.19660418687, 11831.17181797266, 12337.691557711129, 12566.560170393992, 12578.0, 12798.296093714935, 12107.636770896886, 12233.632991010283, 12321.383930803244, 12356.25862448256, 12566.748665026465, 6370.585911915896, 11415.0, 12079.0, 12269.447874845831, 12487.0, 8007.439479236397, 8913.242374398249, 9054.366194366332, 9057.230135438298, 9370.101076456822, 10058.899411005796, 11086.944565277123, 11265.26775759573, 11794.705132371759, 12172.75654486923], "value": 9622.253201612542, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1574.2915687940422, 1603.2945503978249, 1642.8275031121732, 1707.6926153292402, 1793.8206179382055, 8818.4708917465, 9487.573059212335, 9543.093406126407, 9545.0, 9555.37890037148, 8154.592270386486, 8726.38915275931, 9051.230645395133, 9074.72775816725, 9310.255179585634, 2461.606143017122, 2500.962485562718, 2508.9623655645178, 2511.1336588876807, 2526.3305224115625, 2437.8659173745436, 2526.861022643754, 2528.4184637533376, 2533.7086235083016, 2533.949321013579, 2455.533448644761, 2473.8144639152138, 2495.1142344467726, 2495.6506089147533, 2496.0639760089966, 2399.7960173385195, 2404.495056038234, 2411.6141417373224, 2441.8412803167917, 2469.493753780471, 2429.5626787178303, 2430.1008626808016, 2436.476157626119, 2442.572549803795, 2465.161844972718, 9400.341976061678, 9418.62325506979, 9465.148136667663, 9474.431534107976, 9493.857592136124, 5525.0883604205255, 5657.462541058634, 5659.632123911973, 5664.348599911022, 5665.830025099253, 3607.152319204985, 3679.061839230979, 3744.3821769408014, 3750.5311836020496, 3760.849566017356, 1144.4907016377715, 1199.9880001199963, 1224.2960297828783, 1225.277086518949, 1232.0882546915307, 13214.471421143142, 13237.86762132376, 13246.33768311571, 13258.66853328665, 13266.0, 4836.0, 4891.73095479752, 4982.825601103944, 5037.546620804145, 5037.596992240612, 11041.613543525938, 11056.391898445663, 11073.0, 11078.833817492745, 11079.058280046125, 5669.092945128759, 5692.430756924357, 5728.656280623176, 5729.398413166641, 5750.597458177887, 4512.52618475062, 4601.194790911577, 5239.502247286502, 5246.895062098737, 5259.947400525945, 4196.916061678749, 4265.0, 4280.293751531025, 4291.678124140702, 4346.5653434656915, 1300.2718477652545, 1304.5499302740538, 1315.3817705678316, 1337.9665508362175, 1354.0, 919.9420666233751, 979.6228452045871, 1030.9639162629271, 1036.4506811389974, 1046.3355917901251, 6144.354842741537, 6270.843228919222, 6388.488920886411, 6420.550561460653, 6491.415772580491, 470.45897218199343, 476.768767147933, 481.2084121619916, 485.7328469341848, 507.49504243277437, 4235.195312890541, 4388.705331927089, 4401.84593539231, 4435.4233949587, 4528.184926713157, 11241.94379028116, 11761.117916156323, 11773.058155347551, 11775.941120294343, 11776.941115294369, 11416.37209953444, 11433.771324573461, 11592.130590205768, 11619.535218591162, 11622.88377116235, 8734.257588104954, 8746.169113934166, 8770.736877893672, 8815.426997245158, 8828.293736501064, 6339.302676705466, 6363.93636063652, 6429.132067171059, 6467.676616169078, 6487.513436492376, 10538.8419173714, 10757.085647720032, 10780.137588993019, 10878.401687906924, 10887.455627218449, 10760.46197690128, 10773.29973551748, 10886.891131088587, 10924.0, 10930.836037459285, 7488.288612581796, 7995.720149794729, 8159.673613055649, 8337.874931875906, 8398.916010839814, 9004.234640055669, 9106.544672766468, 9114.544272786201, 9118.133777291147, 9121.407108537924, 7947.682092716458, 8002.239787220205, 8081.636326365446, 8165.836683266182, 8175.059868115243, 7732.497387669783, 7863.095743989515, 7871.84256314882, 7951.443398962017, 7996.200379961957, 6882.552634078969, 7082.752103676346, 7186.461015423969, 7190.4607154464675, 7192.640367981476, 5620.775168993195, 5676.375598684059, 5740.0815869460685, 5743.6840973745175, 5762.078067509178, 5447.836564903059, 5470.097433923358, 5473.67157970523, 5572.247746554163, 5625.46558076982], "value": 4839.111893978571, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.73040489228185, 355.51294726225075, 356.613074813827, 357.6584361934353, 366.2217787202194, 5466.535344495717, 5468.890622187554, 5480.588955828314, 5493.533049690771, 5536.612437129403, 5384.057789886764, 5434.402215756264, 5459.836204913849, 5464.0, 5465.672059676423, 3774.622537746231, 4119.382092686099, 4228.302330115527, 4268.295731204347, 4337.783110844461, 4315.611594956454, 4321.308590625501, 4325.74045557266, 4335.284678028122, 4350.7607081610495, 4318.740875547462, 4323.308270676693, 4329.372241025049, 4332.718373305741, 4334.634590104123, 4079.2861249281555, 4250.659947204216, 4297.839583312488, 4322.524522302544, 4334.523202447728, 3793.468914351993, 3794.6395092466173, 3798.5631652360057, 3799.639034291738, 3807.505024346845, 5283.260343551906, 5294.470552944713, 5314.176302673091, 5325.121354976424, 5346.893062138735, 4766.713997160146, 4830.450103317145, 4845.806167753286, 4849.199882019462, 4895.9755201224125, 5673.212937924541, 5673.6595804251465, 5685.573581981326, 5686.658800471985, 5696.829095127153, 4157.210130075307, 4166.1251137261215, 4168.374743788426, 4174.0, 4177.248095342837, 5657.943420565783, 5834.424705329538, 5835.679037652927, 5840.766369345221, 5876.500497457721, 4199.4120823084795, 4220.409142720022, 4227.619514243733, 4232.830686772495, 4234.661227101825, 4267.573242675708, 4285.357196420562, 4289.1421715657, 4294.82820687169, 4297.097609501977, 4685.5782979532005, 4816.133096042746, 4834.927476087862, 4851.442084160367, 4855.635827312895, 5606.495415412633, 5633.155026746021, 5636.872625474922, 5644.774209031675, 5681.602287839894, 1475.3582191746625, 1488.7543555313468, 1494.2454060699276, 1494.8355680875095, 1495.865372116493, 915.6428992692855, 958.7105343313233, 958.9786876975999, 959.5777857742571, 969.3941286695815, 5012.899742005139, 5013.1978883379215, 5016.219242169051, 5025.617955062387, 5030.968651426449, 5164.251183578435, 5182.144946083854, 5205.583553315726, 5212.504812042926, 5219.347581552306, 4954.876128096755, 4975.676581022294, 5017.89964200714, 5261.289725887033, 5617.297837770278, 3129.702678245563, 3137.341158356771, 3138.4821504451966, 3158.6367567730003, 3160.4627213373783, 6104.4505994460715, 6108.5723999319625, 6108.6866323740605, 6119.755209791648, 6137.9079313810325, 5757.481826635623, 5758.337791153987, 6020.337762846083, 6037.9396206038255, 6050.818475445656, 5899.705014749331, 6089.269287685506, 6090.568716351654, 6120.7551697931585, 6123.38766123393, 5923.229980102559, 5926.170336153028, 5930.940690593039, 5933.436323549256, 5944.405559444021, 5615.410381909841, 5840.853978650568, 5857.355690873916, 5879.118132280021, 5884.617499862496, 6155.784547540815, 6155.907661385172, 6158.414950579688, 6166.784162554289, 6176.783812566719, 4054.39184122384, 4257.297545904891, 4259.297215959332, 4263.55232700562, 4263.827447451976, 5882.176495290577, 5889.672335130809, 5890.410958904075, 5895.410458954242, 5895.64626122434, 5756.56825738063, 5763.308402991668, 5764.106563482667, 5774.682392468287, 5792.275965504312, 5589.55283577321, 5769.09619825465, 5769.624974376652, 5776.306843178845, 5789.681567513827, 5759.65442073477, 5770.971145144247, 5779.797707080232, 5787.131930210502, 5791.218185545066, 5586.497215250647, 5733.627314224729, 5801.245838041028, 5809.50619197373, 5821.214136091743, 5613.775448981995, 5628.846086552328, 5633.802816901389, 5639.718014099197, 5643.548516118619], "value": 4355.541700676312, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [769.6036541181293, 792.2236208515654, 799.1728560939429, 834.2741814621279, 918.6646873891032, 16927.915360423198, 17410.477685669433, 17756.556086097855, 17925.372611958584, 18068.457946261617, 16131.516054518383, 16291.83708162918, 16315.755263671055, 16316.83683163171, 16335.26491307891, 748.5059860492076, 878.3105262369041, 885.8405487012351, 897.5198268926126, 900.3967341880923, 895.8611415230649, 899.7795540092686, 904.4573256046385, 904.4799240436743, 905.1220316293185, 949.6059135458811, 953.31361419778, 954.1079091049855, 954.2699834626517, 955.1594596754846, 948.0850978805416, 949.767307009787, 950.1591091883698, 950.4202436513721, 950.4582388038821, 696.6203419136583, 699.5487910297829, 700.4186525184085, 701.2951983256845, 701.7894631610548, 14280.357383917644, 14312.42750289987, 14317.642058948506, 14335.92832035833, 14350.64123396913, 14544.0, 14666.56000319992, 15123.773143402857, 15333.386664533406, 16470.505884823473, 14218.928905355408, 14413.305200446817, 14424.639384015378, 15014.474493392789, 15016.774748378783, 4834.395700537433, 4837.419509658828, 4872.926906096412, 4997.225430058311, 5035.0685123252315, 92901.0, 93033.53483232608, 93359.53320233355, 94193.0, 94286.0, 12275.74874897318, 12444.31556264396, 12496.562620308245, 12602.747945041048, 12796.744065118646, 21893.0, 22671.659925101136, 22820.31539053831, 22832.429189270402, 22866.657000145013, 9260.49067301305, 9313.720588382359, 9370.625174992923, 9474.242060635133, 9705.272104592184, 4747.667663263538, 4944.9258261126115, 5027.79888804451, 5066.442691303953, 5075.771590278447, 3865.9226815463535, 3995.9400608990886, 4017.9397309040387, 4029.1538776857187, 4041.2321658884716, 694.0768777525878, 703.3423748794893, 714.0182249407065, 715.2775696546526, 726.8655298769745, 740.9814754631177, 748.6631016042759, 756.8902509136144, 765.9578723170167, 782.0631003003397, 10042.358716964953, 10403.687889363331, 10823.0, 11024.228304018641, 11500.827487587692, 726.9491135620456, 755.720383458124, 756.2097607999654, 791.5646394483011, 793.1592511937365, 5032.3206367139965, 5078.619103567247, 5099.133147364957, 5181.274621552986, 5259.184826351921, 127103.0, 134676.0, 134813.0, 138617.3069134648, 138771.0, 113543.0, 114948.0, 115280.0, 116854.0, 117961.41019294849, 62626.0, 95464.0, 96608.0, 104981.95018049875, 106661.46669266604, 9500.320084959138, 9902.257330700082, 10273.486325683536, 10385.909479504544, 10465.68602941913, 41666.79166604148, 42377.36433953432, 47799.52200477951, 48163.759181203866, 49544.75227623839, 76598.23401766134, 79202.6039869797, 79418.0, 80614.0, 84624.0, 13207.471701132232, 14386.856131438552, 14474.203918784568, 14540.636484087983, 14955.700885982436, 14778.48275310359, 15588.92205538965, 16202.108884011492, 16874.74687879658, 17123.82876171272, 15148.772768408704, 15151.621209469851, 16102.114383709008, 16993.150342483073, 17045.659086818443, 14020.859791402363, 15146.318415671101, 15256.542303730463, 16651.50045498637, 16698.74951875747, 14669.559913202622, 15735.134567598892, 15911.602209944844, 16413.343466261216, 16428.096454695107, 11709.824352634887, 11812.350320732443, 11926.165168438472, 12164.695882603006, 12554.0, 11639.825402618799, 11904.821427678766, 11969.64091077269, 11976.880231197576, 12134.817977730165], "value": 8342.789568441736, "unit": "score"}], "name": "dromaeo_css", "value": 7709.747585364999, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 121.0, 111.0, 111.0, 111.0, 110.0, 110.0, 111.0, 111.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 120.0, 140.0, 176.0, 116.0, 146.0, 139.0, 116.0, 161.0, 115.0], "value": 137.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 161.0, 159.0, 159.0, 161.0, 161.0, 159.0, 160.0, 158.0], "value": 159.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 175.0, 75.0, 77.0, 73.0, 74.0, 170.0, 75.0], "value": 94.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 150.0, 84.0, 84.0, 83.0, 147.0, 83.0, 83.0, 139.0], "value": 101.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 118.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 50.0, 50.0, 50.0, 51.0, 52.0, 49.0, 50.0, 50.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 59.0, 61.0, 61.0, 60.0, 60.0, 60.0, 61.0, 61.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 72.0, 75.0, 77.0, 78.0, 72.0, 72.0, 73.0, 77.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 120.0, 122.0, 121.0, 120.0, 127.0, 118.0, 123.0, 166.0, 125.0], "value": 126.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 154.0, 151.0, 155.0, 148.0, 149.0, 151.0, 151.0, 160.0, 151.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 62.0, 61.0, 62.0, 60.0, 67.0, 66.0, 64.0, 60.0], "value": 63.4, "unit": "score"}], "name": "kraken", "value": 1489.4000000000003, "alertThreshold": 2.0}]} 10:38:39 INFO - Return code: 0 10:38:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:38:39 INFO - # TBPL SUCCESS # 10:38:39 INFO - Running post-action listener: _resource_record_post_action 10:38:39 INFO - [mozharness: 2016-10-11 17:38:39.721142Z] Finished run-tests step (success) 10:38:39 INFO - Running post-run listener: _resource_record_post_run 10:38:39 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 7467008; Write bytes: 416591872; Read time: 1244; Write time: 359472 10:38:39 INFO - TinderboxPrint: CPU usage
12.9% 10:38:39 INFO - TinderboxPrint: I/O read bytes / time
7,467,008 / 1,244 10:38:39 INFO - TinderboxPrint: I/O write bytes / time
416,591,872 / 359,472 10:38:39 INFO - TinderboxPrint: CPU idle
8,271.4 (87.1%) 10:38:39 INFO - TinderboxPrint: CPU user
1,179.4 (12.4%) 10:38:39 INFO - TinderboxPrint: Swap in / out
0 / 0 10:38:39 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71331840; Read time: 0; Write time: 49736 10:38:39 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 5263360; Write bytes: 344891392; Read time: 1068; Write time: 309692 10:38:40 INFO - Running post-run listener: _upload_blobber_files 10:38:40 INFO - Blob upload gear active. 10:38:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:38:40 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 10:38:40 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'] 10:38:40 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 10:38:40 INFO - (blobuploader) - INFO - Open directory for files ... 10:38:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:38:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:38:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:41 INFO - (blobuploader) - INFO - Done attempting. 10:38:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:38:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:38:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:45 INFO - (blobuploader) - INFO - Done attempting. 10:38:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:38:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:38:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:45 INFO - (blobuploader) - INFO - Done attempting. 10:38:45 INFO - (blobuploader) - INFO - Iteration through files over. 10:38:45 INFO - Return code: 0 10:38:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:38:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:38:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f75534b2e939ed5e6414744d335d8234e6f00ef64affdaf8e0c99c7e00febd40d527cb85647eb63f5ebc5effa8109fbdc0acfab1b909226507e29112d1894aea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cf741aa363dca0df7dfcd6c3928a0f43a05de961a85f0c0f8c769aafdf1950a4f185452137f32e5507b826f47b095e4a2a069b38520898575cda65c594161303", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/73d8a2c934d8201b6b39afa4efdb7d983b3db172f3dd26099ff20d70531f1189be1f2e2824bd045f511e98b917dda24bcfbb9f27ef97b69c0a61de16df1fc90d"} 10:38:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:38:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:38:45 INFO - Contents: 10:38:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f75534b2e939ed5e6414744d335d8234e6f00ef64affdaf8e0c99c7e00febd40d527cb85647eb63f5ebc5effa8109fbdc0acfab1b909226507e29112d1894aea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cf741aa363dca0df7dfcd6c3928a0f43a05de961a85f0c0f8c769aafdf1950a4f185452137f32e5507b826f47b095e4a2a069b38520898575cda65c594161303", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/73d8a2c934d8201b6b39afa4efdb7d983b3db172f3dd26099ff20d70531f1189be1f2e2824bd045f511e98b917dda24bcfbb9f27ef97b69c0a61de16df1fc90d"} 10:38:45 INFO - Running post-run listener: copy_logs_to_upload_dir 10:38:45 INFO - Copying logs to upload dir... 10:38:45 INFO - mkdir: /builds/slave/test/build/upload/logs 10:38:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1680.422631 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-10-11 10:38:45.962860) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-11 10:38:45.966105) ========= 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=920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466 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/f75534b2e939ed5e6414744d335d8234e6f00ef64affdaf8e0c99c7e00febd40d527cb85647eb63f5ebc5effa8109fbdc0acfab1b909226507e29112d1894aea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cf741aa363dca0df7dfcd6c3928a0f43a05de961a85f0c0f8c769aafdf1950a4f185452137f32e5507b826f47b095e4a2a069b38520898575cda65c594161303", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/73d8a2c934d8201b6b39afa4efdb7d983b3db172f3dd26099ff20d70531f1189be1f2e2824bd045f511e98b917dda24bcfbb9f27ef97b69c0a61de16df1fc90d"} build_url:https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011420 build_url: 'https://queue.taskcluster.net/v1/task/I2X_FhAkSt-TNl_uGRZoYQ/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/f75534b2e939ed5e6414744d335d8234e6f00ef64affdaf8e0c99c7e00febd40d527cb85647eb63f5ebc5effa8109fbdc0acfab1b909226507e29112d1894aea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cf741aa363dca0df7dfcd6c3928a0f43a05de961a85f0c0f8c769aafdf1950a4f185452137f32e5507b826f47b095e4a2a069b38520898575cda65c594161303", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/73d8a2c934d8201b6b39afa4efdb7d983b3db172f3dd26099ff20d70531f1189be1f2e2824bd045f511e98b917dda24bcfbb9f27ef97b69c0a61de16df1fc90d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-11 10:38:45.991246) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 10:38:45.991578) ========= 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=920470bf432669ad7e3a5387000001ca-1476205795.63131-1295059466 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004423 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 10:38:46.074735) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-11 10:38:46.075229) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-11 10:38:46.075625) ========= ========= Total master_lag: 0.16 =========