builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-028 starttime: 1474302601.7 results: success (0) buildid: 20160919064031 builduid: ae1839bfaf4840dab36e395afce28275 revision: 01d67bfe6c8198416ec190cbc216d953c1625b94 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 09:30:01.703434) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 09:30:01.704043) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 09:30:01.704363) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 09:30:01.720074) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 09:30:01.720494) ========= 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=836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 09:30:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 95.6M=0s 2016-09-19 09:30:02 (95.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.074266 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 09:30:01.809313) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 09:30:01.809630) ========= 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=836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015536 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 09:30:01.859557) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 09:30:01.859931) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 01d67bfe6c8198416ec190cbc216d953c1625b94 --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 01d67bfe6c8198416ec190cbc216d953c1625b94 --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=836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 09:30:02,809 truncating revision to first 12 chars 2016-09-19 09:30:02,810 Setting DEBUG logging. 2016-09-19 09:30:02,810 attempt 1/10 2016-09-19 09:30:02,810 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/01d67bfe6c81?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 09:30:04,216 unpacking tar archive at: mozilla-beta-01d67bfe6c81/testing/mozharness/ program finished with exit code 0 elapsedTime=1.812009 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 09:30:03.686869) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 09:30:03.687327) ========= script_repo_revision: 01d67bfe6c8198416ec190cbc216d953c1625b94 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 09:30:03.687877) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 09:30:03.688262) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 09:30:03.699007) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-19 09:30:03.699537) ========= /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=836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:30:04 INFO - MultiFileLogger online at 20160919 09:30:04 in /builds/slave/test 09:30:04 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 09:30:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:30:04 INFO - {'append_to_log': False, 09:30:04 INFO - 'base_work_dir': '/builds/slave/test', 09:30:04 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:30:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:30:04 INFO - 'branch': 'Mozilla-Beta', 09:30:04 INFO - 'buildbot_json_path': 'buildprops.json', 09:30:04 INFO - 'config_files': ('talos/linux_config.py',), 09:30:04 INFO - 'default_actions': ('clobber', 09:30:04 INFO - 'read-buildbot-config', 09:30:04 INFO - 'download-and-extract', 09:30:04 INFO - 'populate-webroot', 09:30:04 INFO - 'create-virtualenv', 09:30:04 INFO - 'install', 09:30:04 INFO - 'run-tests'), 09:30:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:30:04 INFO - 'download_minidump_stackwalk': True, 09:30:04 INFO - 'download_symbols': 'ondemand', 09:30:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:30:04 INFO - 'tooltool.py': '/tools/tooltool.py', 09:30:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:30:04 INFO - '/tools/misc-python/virtualenv.py')}, 09:30:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:30:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:30:04 INFO - 'installer_path': 'installer.exe', 09:30:04 INFO - 'log_level': 'info', 09:30:04 INFO - 'log_name': 'talos', 09:30:04 INFO - 'log_to_console': True, 09:30:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:30:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:30:04 INFO - 'opt_config_files': (), 09:30:04 INFO - 'pip_index': False, 09:30:04 INFO - 'sps_profile': False, 09:30:04 INFO - 'sps_profile_interval': 0, 09:30:04 INFO - 'suite': 'dromaeojs', 09:30:04 INFO - 'system_bits': '32', 09:30:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:30:04 INFO - 'title': 'talos-linux64-ix-028', 09:30:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:30:04 INFO - 'use_talos_json': True, 09:30:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:30:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:30:04 INFO - 'work_dir': 'build'} 09:30:04 INFO - [mozharness: 2016-09-19 16:30:04.739525Z] Running clobber step. 09:30:04 INFO - Running pre-action listener: _resource_record_pre_action 09:30:04 INFO - Running main action method: clobber 09:30:04 INFO - rmtree: /builds/slave/test/build 09:30:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:30:05 INFO - Running post-action listener: _resource_record_post_action 09:30:05 INFO - [mozharness: 2016-09-19 16:30:05.198956Z] Finished clobber step (success) 09:30:05 INFO - [mozharness: 2016-09-19 16:30:05.199059Z] Running read-buildbot-config step. 09:30:05 INFO - Running pre-action listener: _resource_record_pre_action 09:30:05 INFO - Running main action method: read_buildbot_config 09:30:05 INFO - Using buildbot properties: 09:30:05 INFO - { 09:30:05 INFO - "project": "", 09:30:05 INFO - "product": "firefox", 09:30:05 INFO - "script_repo_revision": "production", 09:30:05 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:30:05 INFO - "repository": "", 09:30:05 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:30:05 INFO - "buildid": "20160919064031", 09:30:05 INFO - "pgo_build": "True", 09:30:05 INFO - "basedir": "/builds/slave/test", 09:30:05 INFO - "buildnumber": 239, 09:30:05 INFO - "slavename": "talos-linux64-ix-028", 09:30:05 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:30:05 INFO - "platform": "ubuntu64_hw", 09:30:05 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:30:05 INFO - "branch": "mozilla-beta", 09:30:05 INFO - "repo_path": "releases/mozilla-beta", 09:30:05 INFO - "revision": "01d67bfe6c8198416ec190cbc216d953c1625b94", 09:30:05 INFO - "stage_platform": "linux64-pgo", 09:30:05 INFO - "builduid": "ae1839bfaf4840dab36e395afce28275", 09:30:05 INFO - "slavebuilddir": "test" 09:30:05 INFO - } 09:30:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 09:30:05 INFO - Running post-action listener: _resource_record_post_action 09:30:05 INFO - [mozharness: 2016-09-19 16:30:05.201511Z] Finished read-buildbot-config step (success) 09:30:05 INFO - [mozharness: 2016-09-19 16:30:05.201601Z] Running download-and-extract step. 09:30:05 INFO - Running pre-action listener: _resource_record_pre_action 09:30:05 INFO - Running main action method: download_and_extract 09:30:05 INFO - mkdir: /builds/slave/test/build/tests 09:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:05 INFO - https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:30:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:30:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:30:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:30:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:30:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:30:05 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:30:05 INFO - retry: Failed, sleeping 30 seconds before retrying 09:30:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:30:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:30:35 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:30:35 INFO - retry: Failed, sleeping 60 seconds before retrying 09:31:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:31:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:31:35 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:31:35 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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! 09:31:35 INFO - Caught exception: HTTP Error 404: Not Found 09:31:35 INFO - Caught exception: HTTP Error 404: Not Found 09:31:35 INFO - Caught exception: HTTP Error 404: Not Found 09:31:35 INFO - trying https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:31:35 INFO - Downloading https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:31:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:31:35 INFO - Downloaded 1327 bytes. 09:31:35 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:31:35 INFO - Using the following test package requirements: 09:31:35 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 09:31:35 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:31:35 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:31:35 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:31:35 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 09:31:35 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:31:35 INFO - u'jsshell-linux-x86_64.zip'], 09:31:35 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:31:35 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:31:35 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 09:31:35 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:31:35 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 09:31:35 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:31:35 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 09:31:35 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:31:35 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:31:35 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:31:35 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:31:35 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:31: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')]} 09:31:35 INFO - https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:31:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:31:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:31:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:31:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:31:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:31:35 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:31:35 INFO - retry: Failed, sleeping 30 seconds before retrying 09:32:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:32:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:32:05 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:32:05 INFO - retry: Failed, sleeping 60 seconds before retrying 09:33:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:33:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:33:05 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:33:05 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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! 09:33:05 INFO - Caught exception: HTTP Error 404: Not Found 09:33:05 INFO - Caught exception: HTTP Error 404: Not Found 09:33:05 INFO - Caught exception: HTTP Error 404: Not Found 09:33:05 INFO - trying https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:33:05 INFO - Downloading https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:33:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:33:06 INFO - Downloaded 21772072 bytes. 09:33:06 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'] 09:33:06 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 09:33:07 INFO - Return code: 0 09:33:07 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 09:33:07 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')]} 09:33:07 INFO - https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:33:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:33:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:33:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:33:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:33:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:33:07 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:33:07 INFO - retry: Failed, sleeping 30 seconds before retrying 09:33:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:33:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:33:38 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:33:38 INFO - retry: Failed, sleeping 60 seconds before retrying 09:34:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:34:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:34:38 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:34:38 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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! 09:34:38 INFO - Caught exception: HTTP Error 404: Not Found 09:34:38 INFO - Caught exception: HTTP Error 404: Not Found 09:34:38 INFO - Caught exception: HTTP Error 404: Not Found 09:34:38 INFO - trying https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:34:38 INFO - Downloading https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:34:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:34:38 INFO - Downloaded 21772072 bytes. 09:34:38 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'] 09:34:38 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 09:34:40 INFO - Return code: 0 09:34:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:40 INFO - https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:34:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:34:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:34:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:34:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:34:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:34:40 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:34:40 INFO - retry: Failed, sleeping 30 seconds before retrying 09:35:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:35:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:35:10 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:35:10 INFO - retry: Failed, sleeping 60 seconds before retrying 09:36:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:36:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:36:10 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:36:10 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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! 09:36:10 INFO - Caught exception: HTTP Error 404: Not Found 09:36:10 INFO - Caught exception: HTTP Error 404: Not Found 09:36:10 INFO - Caught exception: HTTP Error 404: Not Found 09:36:10 INFO - trying https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:36:10 INFO - Downloading https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:36:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:36:11 INFO - Downloaded 14413740 bytes. 09:36:11 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'] 09:36:11 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 09:36:11 INFO - Return code: 0 09:36:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:11 INFO - https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:36:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:36:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:36:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:36:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:36:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:36:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:36:11 INFO - retry: Failed, sleeping 30 seconds before retrying 09:36:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:36:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:36:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:36:41 INFO - retry: Failed, sleeping 60 seconds before retrying 09:37:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:37:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:37:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:37:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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! 09:37:41 INFO - Caught exception: HTTP Error 404: Not Found 09:37:41 INFO - Caught exception: HTTP Error 404: Not Found 09:37:41 INFO - Caught exception: HTTP Error 404: Not Found 09:37:41 INFO - trying https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:37:41 INFO - Downloading https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:37:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 09:37:42 INFO - Downloaded 56767399 bytes. 09:37:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:37:42 INFO - mkdir: /builds/slave/test/properties 09:37:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:37:42 INFO - Writing to file /builds/slave/test/properties/build_url 09:37:42 INFO - Contents: 09:37:42 INFO - build_url:https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:37:43 INFO - Running post-action listener: _resource_record_post_action 09:37:43 INFO - Running post-action listener: set_extra_try_arguments 09:37:43 INFO - [mozharness: 2016-09-19 16:37:43.290863Z] Finished download-and-extract step (success) 09:37:43 INFO - [mozharness: 2016-09-19 16:37:43.291137Z] Running populate-webroot step. 09:37:43 INFO - Running pre-action listener: _resource_record_pre_action 09:37:43 INFO - Running main action method: populate_webroot 09:37:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:37:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:37:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:37:43 INFO - '--tppagecycles', 09:37:43 INFO - '7'], 09:37:43 INFO - 'tests': ['tsvgm']}}, 09:37:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:37:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:37:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:37:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:37:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:37:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:37:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:43 INFO - 'talos_options': ['--disable-e10s'], 09:37:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:43 INFO - 'talos_options': ['--disable-e10s'], 09:37:43 INFO - 'tests': ['damp', 'tps']}, 09:37:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:43 INFO - 'tests': ['damp', 'tps']}, 09:37:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:37:43 INFO - 'tests': ['dromaeo_dom']}, 09:37:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:37:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:37:43 INFO - 'tests': ['basic_compositor_video']}, 09:37:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:37:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:37:43 INFO - 'tests': ['a11yr', 09:37:43 INFO - 'ts_paint', 09:37:43 INFO - 'tpaint', 09:37:43 INFO - 'sessionrestore', 09:37:43 INFO - 'sessionrestore_no_auto_restore', 09:37:43 INFO - 'tabpaint']}, 09:37:43 INFO - 'other-e10s': {'tests': ['a11yr', 09:37:43 INFO - 'ts_paint', 09:37:43 INFO - 'tpaint', 09:37:43 INFO - 'sessionrestore', 09:37:43 INFO - 'sessionrestore_no_auto_restore', 09:37:43 INFO - 'tabpaint']}, 09:37:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:37:43 INFO - 'ts_paint', 09:37:43 INFO - 'tpaint', 09:37:43 INFO - 'sessionrestore', 09:37:43 INFO - 'sessionrestore_no_auto_restore', 09:37:43 INFO - 'tabpaint']}, 09:37:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:37:43 INFO - 'ts_paint', 09:37:43 INFO - 'tpaint', 09:37:43 INFO - 'sessionrestore', 09:37:43 INFO - 'sessionrestore_no_auto_restore', 09:37:43 INFO - 'tabpaint']}, 09:37:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:37:43 INFO - 'tests': ['a11yr', 09:37:43 INFO - 'ts_paint', 09:37:43 INFO - 'tpaint', 09:37:43 INFO - 'sessionrestore', 09:37:43 INFO - 'sessionrestore_no_auto_restore', 09:37:43 INFO - 'tabpaint']}, 09:37:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:37:43 INFO - 'tests': ['a11yr', 09:37:43 INFO - 'ts_paint', 09:37:43 INFO - 'tpaint', 09:37:43 INFO - 'sessionrestore', 09:37:43 INFO - 'sessionrestore_no_auto_restore', 09:37:43 INFO - 'tabpaint']}, 09:37:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:37:43 INFO - 'tests': ['tsvgx', 09:37:43 INFO - 'tsvgr_opacity', 09:37:43 INFO - 'tart', 09:37:43 INFO - 'tscrollx', 09:37:43 INFO - 'cart']}, 09:37:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:37:43 INFO - 'tsvgr_opacity', 09:37:43 INFO - 'tart', 09:37:43 INFO - 'tscrollx', 09:37:43 INFO - 'cart']}, 09:37:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:43 INFO - 'talos_options': ['--disable-e10s'], 09:37:43 INFO - 'tests': ['tp5o']}, 09:37:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:43 INFO - 'tests': ['tp5o']}, 09:37:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:43 INFO - 'talos_options': ['--disable-e10s', 09:37:43 INFO - '--xperf_path', 09:37:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:43 INFO - 'tests': ['tp5n']}, 09:37:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:43 INFO - 'talos_options': ['--xperf_path', 09:37:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:43 INFO - 'tests': ['tp5n']}}, 09:37:43 INFO - 'talos.zip': {'path': '', 09:37:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:37:43 INFO - Running post-action listener: _resource_record_post_action 09:37:43 INFO - [mozharness: 2016-09-19 16:37:43.306902Z] Finished populate-webroot step (success) 09:37:43 INFO - [mozharness: 2016-09-19 16:37:43.307011Z] Running create-virtualenv step. 09:37:43 INFO - Running pre-action listener: _resource_record_pre_action 09:37:43 INFO - Running main action method: create_virtualenv 09:37:43 INFO - Creating virtualenv /builds/slave/test/build/venv 09:37:43 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 09:37:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:37:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:37:43 INFO - Using real prefix '/usr' 09:37:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:37:43 INFO - Installing distribute.............................................................................................................................................................................................done. 09:37:45 INFO - Installing pip.................done. 09:37:45 INFO - Return code: 0 09:37:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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')]} 09:37:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:45 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 0x1dce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d3b0>, '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 0x208e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208e740>, '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': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', '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 09:37:45 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 09:37:45 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 09:37:45 INFO - Using env: {'DISPLAY': ':0', 09:37:45 INFO - 'HOME': '/home/cltbld', 09:37:45 INFO - 'LANG': 'en_US.UTF-8', 09:37:45 INFO - 'LANGUAGE': 'en_US:en', 09:37:45 INFO - 'LOGNAME': 'cltbld', 09:37:45 INFO - 'MAIL': '/var/mail/cltbld', 09:37:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:45 INFO - 'MOZ_NO_REMOTE': '1', 09:37:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:45 INFO - 'NO_EM_RESTART': '1', 09:37:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:45 INFO - 'PWD': '/builds/slave/test', 09:37:45 INFO - 'SHELL': '/bin/bash', 09:37:45 INFO - 'SHLVL': '1', 09:37:45 INFO - 'TERM': 'linux', 09:37:45 INFO - 'TMOUT': '86400', 09:37:45 INFO - 'USER': 'cltbld', 09:37:45 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', 09:37:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:45 INFO - '_': '/tools/buildbot/bin/python'} 09:37:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:46 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 09:37:46 INFO - Cleaning up... 09:37:46 INFO - Return code: 0 09:37:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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')]} 09:37:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1dce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d3b0>, '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 0x208e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208e740>, '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': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', '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 09:37:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:37:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:37:46 INFO - Using env: {'DISPLAY': ':0', 09:37:46 INFO - 'HOME': '/home/cltbld', 09:37:46 INFO - 'LANG': 'en_US.UTF-8', 09:37:46 INFO - 'LANGUAGE': 'en_US:en', 09:37:46 INFO - 'LOGNAME': 'cltbld', 09:37:46 INFO - 'MAIL': '/var/mail/cltbld', 09:37:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:46 INFO - 'MOZ_NO_REMOTE': '1', 09:37:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:46 INFO - 'NO_EM_RESTART': '1', 09:37:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:46 INFO - 'PWD': '/builds/slave/test', 09:37:46 INFO - 'SHELL': '/bin/bash', 09:37:46 INFO - 'SHLVL': '1', 09:37:46 INFO - 'TERM': 'linux', 09:37:46 INFO - 'TMOUT': '86400', 09:37:46 INFO - 'USER': 'cltbld', 09:37:46 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', 09:37:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:46 INFO - '_': '/tools/buildbot/bin/python'} 09:37:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:46 INFO - Downloading/unpacking psutil>=3.1.1 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:37:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:48 INFO - Installing collected packages: psutil 09:37:48 INFO - Running setup.py install for psutil 09:37:48 INFO - building 'psutil._psutil_linux' extension 09:37:48 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 09:37:48 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 09:37:48 INFO - building 'psutil._psutil_posix' extension 09:37:48 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 09:37:48 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 09:37:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:48 INFO - Successfully installed psutil 09:37:48 INFO - Cleaning up... 09:37:48 INFO - Return code: 0 09:37:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:37:48 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')]} 09:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:48 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')]} 09:37:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:48 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 0x1dce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d3b0>, '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 0x208e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208e740>, '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': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', '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 09:37:48 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 09:37:48 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 09:37:48 INFO - Using env: {'DISPLAY': ':0', 09:37:48 INFO - 'HOME': '/home/cltbld', 09:37:48 INFO - 'LANG': 'en_US.UTF-8', 09:37:48 INFO - 'LANGUAGE': 'en_US:en', 09:37:48 INFO - 'LOGNAME': 'cltbld', 09:37:48 INFO - 'MAIL': '/var/mail/cltbld', 09:37:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:48 INFO - 'MOZ_NO_REMOTE': '1', 09:37:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:48 INFO - 'NO_EM_RESTART': '1', 09:37:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:48 INFO - 'PWD': '/builds/slave/test', 09:37:48 INFO - 'SHELL': '/bin/bash', 09:37:48 INFO - 'SHLVL': '1', 09:37:48 INFO - 'TERM': 'linux', 09:37:48 INFO - 'TMOUT': '86400', 09:37:48 INFO - 'USER': 'cltbld', 09:37:48 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', 09:37:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:48 INFO - '_': '/tools/buildbot/bin/python'} 09:37:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:37:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:37:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:37:51 INFO - Installing collected packages: mozsystemmonitor 09:37:51 INFO - Running setup.py install for mozsystemmonitor 09:37:51 INFO - Successfully installed mozsystemmonitor 09:37:51 INFO - Cleaning up... 09:37:51 INFO - Return code: 0 09:37:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:37:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:51 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 0x1dce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d3b0>, '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 0x208e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208e740>, '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': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', '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 09:37:51 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 09:37:51 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 09:37:51 INFO - Using env: {'DISPLAY': ':0', 09:37:51 INFO - 'HOME': '/home/cltbld', 09:37:51 INFO - 'LANG': 'en_US.UTF-8', 09:37:51 INFO - 'LANGUAGE': 'en_US:en', 09:37:51 INFO - 'LOGNAME': 'cltbld', 09:37:51 INFO - 'MAIL': '/var/mail/cltbld', 09:37:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:51 INFO - 'MOZ_NO_REMOTE': '1', 09:37:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:51 INFO - 'NO_EM_RESTART': '1', 09:37:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:51 INFO - 'PWD': '/builds/slave/test', 09:37:51 INFO - 'SHELL': '/bin/bash', 09:37:51 INFO - 'SHLVL': '1', 09:37:51 INFO - 'TERM': 'linux', 09:37:51 INFO - 'TMOUT': '86400', 09:37:51 INFO - 'USER': 'cltbld', 09:37:51 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', 09:37:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:51 INFO - '_': '/tools/buildbot/bin/python'} 09:37:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:51 INFO - Downloading/unpacking jsonschema==2.5.1 09:37:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:37:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:37:53 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 09:37:53 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 09:37:53 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 09:37:53 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 09:37:53 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 09:37:53 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 09:37:53 INFO - Downloading functools32-3.2.3-2.tar.gz 09:37:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:37:53 INFO - warning: no files found matching '*.txt' 09:37:53 INFO - no previously-included directories found matching 'build' 09:37:53 INFO - no previously-included directories found matching 'dist' 09:37:53 INFO - no previously-included directories found matching '.git*' 09:37:53 INFO - Installing collected packages: jsonschema, functools32 09:37:53 INFO - Running setup.py install for functools32 09:37:53 INFO - warning: no files found matching '*.txt' 09:37:53 INFO - no previously-included directories found matching 'build' 09:37:53 INFO - no previously-included directories found matching 'dist' 09:37:53 INFO - no previously-included directories found matching '.git*' 09:37:53 INFO - Successfully installed jsonschema functools32 09:37:53 INFO - Cleaning up... 09:37:53 INFO - Return code: 0 09:37:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:37:53 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')]} 09:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:53 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')]} 09:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:53 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 0x1dce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d3b0>, '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 0x208e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208e740>, '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': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', '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 09:37:53 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 09:37:53 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 09:37:53 INFO - Using env: {'DISPLAY': ':0', 09:37:53 INFO - 'HOME': '/home/cltbld', 09:37:53 INFO - 'LANG': 'en_US.UTF-8', 09:37:53 INFO - 'LANGUAGE': 'en_US:en', 09:37:53 INFO - 'LOGNAME': 'cltbld', 09:37:53 INFO - 'MAIL': '/var/mail/cltbld', 09:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:53 INFO - 'MOZ_NO_REMOTE': '1', 09:37:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:53 INFO - 'NO_EM_RESTART': '1', 09:37:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:53 INFO - 'PWD': '/builds/slave/test', 09:37:53 INFO - 'SHELL': '/bin/bash', 09:37:53 INFO - 'SHLVL': '1', 09:37:53 INFO - 'TERM': 'linux', 09:37:53 INFO - 'TMOUT': '86400', 09:37:53 INFO - 'USER': 'cltbld', 09:37:53 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', 09:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:53 INFO - '_': '/tools/buildbot/bin/python'} 09:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:37:53 INFO - Cleaning up... 09:37:53 INFO - Return code: 0 09:37:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:37:53 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')]} 09:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:53 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')]} 09:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:53 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 0x1dce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d3b0>, '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 0x208e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208e740>, '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': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', '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 09:37:53 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 09:37:53 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 09:37:53 INFO - Using env: {'DISPLAY': ':0', 09:37:53 INFO - 'HOME': '/home/cltbld', 09:37:53 INFO - 'LANG': 'en_US.UTF-8', 09:37:53 INFO - 'LANGUAGE': 'en_US:en', 09:37:53 INFO - 'LOGNAME': 'cltbld', 09:37:53 INFO - 'MAIL': '/var/mail/cltbld', 09:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:53 INFO - 'MOZ_NO_REMOTE': '1', 09:37:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:53 INFO - 'NO_EM_RESTART': '1', 09:37:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:53 INFO - 'PWD': '/builds/slave/test', 09:37:53 INFO - 'SHELL': '/bin/bash', 09:37:53 INFO - 'SHLVL': '1', 09:37:53 INFO - 'TERM': 'linux', 09:37:53 INFO - 'TMOUT': '86400', 09:37:53 INFO - 'USER': 'cltbld', 09:37:53 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', 09:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:53 INFO - '_': '/tools/buildbot/bin/python'} 09:37:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:54 INFO - Downloading/unpacking blobuploader==1.2.4 09:37:54 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 09:37:54 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 09:37:54 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 09:37:54 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 09:37:54 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 09:37:54 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 09:37:56 INFO - Downloading blobuploader-1.2.4.tar.gz 09:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:37:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:37:56 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 09:37:56 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 09:37:56 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 09:37:56 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 09:37:56 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 09:37:56 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 09:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:37:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:37:56 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 09:37:56 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 09:37:56 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 09:37:56 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 09:37:56 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 09:37:56 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 09:37:56 INFO - Downloading docopt-0.6.1.tar.gz 09:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:37:56 INFO - Installing collected packages: blobuploader, requests, docopt 09:37:56 INFO - Running setup.py install for blobuploader 09:37:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:37:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:37:57 INFO - Running setup.py install for requests 09:37:57 INFO - Running setup.py install for docopt 09:37:57 INFO - Successfully installed blobuploader requests docopt 09:37:57 INFO - Cleaning up... 09:37:57 INFO - Return code: 0 09:37:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:57 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')]} 09:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:57 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')]} 09:37:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:57 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 0x1dce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d3b0>, '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 0x208e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208e740>, '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': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', '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 09:37:57 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 09:37:57 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 09:37:57 INFO - Using env: {'DISPLAY': ':0', 09:37:57 INFO - 'HOME': '/home/cltbld', 09:37:57 INFO - 'LANG': 'en_US.UTF-8', 09:37:57 INFO - 'LANGUAGE': 'en_US:en', 09:37:57 INFO - 'LOGNAME': 'cltbld', 09:37:57 INFO - 'MAIL': '/var/mail/cltbld', 09:37:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:57 INFO - 'MOZ_NO_REMOTE': '1', 09:37:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:57 INFO - 'NO_EM_RESTART': '1', 09:37:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:57 INFO - 'PWD': '/builds/slave/test', 09:37:57 INFO - 'SHELL': '/bin/bash', 09:37:57 INFO - 'SHLVL': '1', 09:37:57 INFO - 'TERM': 'linux', 09:37:57 INFO - 'TMOUT': '86400', 09:37:57 INFO - 'USER': 'cltbld', 09:37:57 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', 09:37:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:57 INFO - '_': '/tools/buildbot/bin/python'} 09:37:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:57 INFO - Running setup.py (path:/tmp/pip-5mgnY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:57 INFO - Running setup.py (path:/tmp/pip-8Mt940-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:57 INFO - Running setup.py (path:/tmp/pip-5xFwl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:57 INFO - Running setup.py (path:/tmp/pip-3XIYiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:58 INFO - Running setup.py (path:/tmp/pip-wdIiiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:58 INFO - Running setup.py (path:/tmp/pip-L9ASPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:58 INFO - Running setup.py (path:/tmp/pip-5hQY_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:58 INFO - Running setup.py (path:/tmp/pip-XgQzSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:58 INFO - Running setup.py (path:/tmp/pip-O_h2rA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:58 INFO - Running setup.py (path:/tmp/pip-MSp1yG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:58 INFO - Running setup.py (path:/tmp/pip-3iWqRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:58 INFO - Running setup.py (path:/tmp/pip-NDcrkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:58 INFO - Running setup.py (path:/tmp/pip-N7mKx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:58 INFO - Running setup.py (path:/tmp/pip-3EuvdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:58 INFO - Running setup.py (path:/tmp/pip-YLnc7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:58 INFO - Running setup.py (path:/tmp/pip-IvR4ok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:58 INFO - Running setup.py (path:/tmp/pip-BoqmRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:37:58 INFO - Running setup.py install for manifestparser 09:37:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Running setup.py install for mozcrash 09:37:59 INFO - Running setup.py install for mozdebug 09:37:59 INFO - Running setup.py install for mozdevice 09:37:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Running setup.py install for mozfile 09:37:59 INFO - Running setup.py install for mozhttpd 09:37:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Running setup.py install for mozinfo 09:37:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Running setup.py install for mozInstall 09:37:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Running setup.py install for mozleak 09:37:59 INFO - Running setup.py install for mozlog 09:37:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Running setup.py install for moznetwork 09:38:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:38:00 INFO - Running setup.py install for mozprocess 09:38:00 INFO - Running setup.py install for mozprofile 09:38:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:38:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:38:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:38:00 INFO - Running setup.py install for mozrunner 09:38:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:38:00 INFO - Running setup.py install for mozscreenshot 09:38:00 INFO - Running setup.py install for moztest 09:38:00 INFO - Running setup.py install for mozversion 09:38:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:38:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:38:00 INFO - Cleaning up... 09:38:00 INFO - Return code: 0 09:38:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:00 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')]} 09:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:00 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')]} 09:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:00 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 0x1dce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d3b0>, '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 0x208e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208e740>, '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': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', '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 09:38:00 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 09:38:00 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 09:38:00 INFO - Using env: {'DISPLAY': ':0', 09:38:00 INFO - 'HOME': '/home/cltbld', 09:38:00 INFO - 'LANG': 'en_US.UTF-8', 09:38:00 INFO - 'LANGUAGE': 'en_US:en', 09:38:00 INFO - 'LOGNAME': 'cltbld', 09:38:00 INFO - 'MAIL': '/var/mail/cltbld', 09:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:00 INFO - 'MOZ_NO_REMOTE': '1', 09:38:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:00 INFO - 'NO_EM_RESTART': '1', 09:38:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:00 INFO - 'PWD': '/builds/slave/test', 09:38:00 INFO - 'SHELL': '/bin/bash', 09:38:00 INFO - 'SHLVL': '1', 09:38:00 INFO - 'TERM': 'linux', 09:38:00 INFO - 'TMOUT': '86400', 09:38:00 INFO - 'USER': 'cltbld', 09:38:00 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', 09:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:00 INFO - '_': '/tools/buildbot/bin/python'} 09:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:00 INFO - Running setup.py (path:/tmp/pip-92rKD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:01 INFO - Running setup.py (path:/tmp/pip-ea5BHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:01 INFO - Running setup.py (path:/tmp/pip-kfeCSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:01 INFO - Running setup.py (path:/tmp/pip-9xRtzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:01 INFO - Running setup.py (path:/tmp/pip-vpHnG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:01 INFO - Running setup.py (path:/tmp/pip-_uU8Rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:01 INFO - Running setup.py (path:/tmp/pip-vQ7A0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:01 INFO - Running setup.py (path:/tmp/pip-jKbDMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:01 INFO - Running setup.py (path:/tmp/pip-v_Ykmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:01 INFO - Running setup.py (path:/tmp/pip-4w_vRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:01 INFO - Running setup.py (path:/tmp/pip-h5jrek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:01 INFO - Running setup.py (path:/tmp/pip-TE4u7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:01 INFO - Running setup.py (path:/tmp/pip-5xFOoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:01 INFO - Running setup.py (path:/tmp/pip-0ff96X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:02 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)) 09:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:02 INFO - Running setup.py (path:/tmp/pip-s02o5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:02 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)) 09:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:02 INFO - Running setup.py (path:/tmp/pip-BaGFIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:02 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)) 09:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:02 INFO - Running setup.py (path:/tmp/pip-3lsshZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:02 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)) 09:38:02 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)) 09:38:02 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)) 09:38:02 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)) 09:38:02 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)) 09:38:02 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)) 09:38:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:38:02 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 09:38:02 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 09:38:02 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 09:38:02 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 09:38:02 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 09:38:02 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 09:38:04 INFO - Downloading blessings-1.6.tar.gz 09:38:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:38:04 INFO - Installing collected packages: blessings 09:38:04 INFO - Running setup.py install for blessings 09:38:04 INFO - Successfully installed blessings 09:38:04 INFO - Cleaning up... 09:38:04 INFO - Return code: 0 09:38:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:38:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:04 INFO - Reading from file tmpfile_stdout 09:38:04 INFO - Current package versions: 09:38:04 INFO - argparse == 1.2.1 09:38:04 INFO - blessings == 1.6 09:38:04 INFO - blobuploader == 1.2.4 09:38:04 INFO - docopt == 0.6.1 09:38:04 INFO - functools32 == 3.2.3-2 09:38:04 INFO - jsonschema == 2.5.1 09:38:04 INFO - manifestparser == 1.1 09:38:04 INFO - mozInstall == 1.12 09:38:04 INFO - mozcrash == 0.17 09:38:04 INFO - mozdebug == 0.1 09:38:04 INFO - mozdevice == 0.48 09:38:04 INFO - mozfile == 1.2 09:38:04 INFO - mozhttpd == 0.7 09:38:04 INFO - mozinfo == 0.9 09:38:04 INFO - mozleak == 0.1 09:38:04 INFO - mozlog == 3.3 09:38:04 INFO - moznetwork == 0.27 09:38:04 INFO - mozprocess == 0.23 09:38:04 INFO - mozprofile == 0.28 09:38:04 INFO - mozrunner == 6.12 09:38:04 INFO - mozscreenshot == 0.1 09:38:04 INFO - mozsystemmonitor == 0.3 09:38:04 INFO - moztest == 0.7 09:38:04 INFO - mozversion == 1.4 09:38:04 INFO - psutil == 3.1.1 09:38:04 INFO - requests == 1.2.3 09:38:04 INFO - wsgiref == 0.1.2 09:38:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38: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')]} 09:38:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38: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')]} 09:38:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:04 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 0x1dce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d3b0>, '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 0x208e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208e740>, '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': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', '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 09:38:04 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 09:38:04 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 09:38:04 INFO - Using env: {'DISPLAY': ':0', 09:38:04 INFO - 'HOME': '/home/cltbld', 09:38:04 INFO - 'LANG': 'en_US.UTF-8', 09:38:04 INFO - 'LANGUAGE': 'en_US:en', 09:38:04 INFO - 'LOGNAME': 'cltbld', 09:38:04 INFO - 'MAIL': '/var/mail/cltbld', 09:38:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:04 INFO - 'MOZ_NO_REMOTE': '1', 09:38:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:04 INFO - 'NO_EM_RESTART': '1', 09:38:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:04 INFO - 'PWD': '/builds/slave/test', 09:38:04 INFO - 'SHELL': '/bin/bash', 09:38:04 INFO - 'SHLVL': '1', 09:38:04 INFO - 'TERM': 'linux', 09:38:04 INFO - 'TMOUT': '86400', 09:38:04 INFO - 'USER': 'cltbld', 09:38:04 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', 09:38:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:04 INFO - '_': '/tools/buildbot/bin/python'} 09:38:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 INFO - Cleaning up... 09:38:04 INFO - Return code: 0 09:38:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:38: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')]} 09:38:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38: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')]} 09:38:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d3b0>, '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 0x208e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208e740>, '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': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', '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 09:38:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:38:04 INFO - Using env: {'DISPLAY': ':0', 09:38:04 INFO - 'HOME': '/home/cltbld', 09:38:04 INFO - 'LANG': 'en_US.UTF-8', 09:38:04 INFO - 'LANGUAGE': 'en_US:en', 09:38:04 INFO - 'LOGNAME': 'cltbld', 09:38:04 INFO - 'MAIL': '/var/mail/cltbld', 09:38:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:04 INFO - 'MOZ_NO_REMOTE': '1', 09:38:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:04 INFO - 'NO_EM_RESTART': '1', 09:38:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:04 INFO - 'PWD': '/builds/slave/test', 09:38:04 INFO - 'SHELL': '/bin/bash', 09:38:04 INFO - 'SHLVL': '1', 09:38:04 INFO - 'TERM': 'linux', 09:38:04 INFO - 'TMOUT': '86400', 09:38:04 INFO - 'USER': 'cltbld', 09:38:04 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', 09:38:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:04 INFO - '_': '/tools/buildbot/bin/python'} 09:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:38:05 INFO - Cleaning up... 09:38:05 INFO - Return code: 0 09:38:05 INFO - Running post-action listener: _resource_record_post_action 09:38:05 INFO - Running post-action listener: _start_resource_monitoring 09:38:05 INFO - Starting resource monitoring. 09:38:05 INFO - [mozharness: 2016-09-19 16:38:05.051096Z] Finished create-virtualenv step (success) 09:38:05 INFO - [mozharness: 2016-09-19 16:38:05.051523Z] Running install step. 09:38:05 INFO - Running pre-action listener: _resource_record_pre_action 09:38:05 INFO - Running main action method: install 09:38:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:05 INFO - Reading from file tmpfile_stdout 09:38:05 INFO - Detecting whether we're running mozinstall >=1.0... 09:38:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:38:05 INFO - Reading from file tmpfile_stdout 09:38:05 INFO - Output received: 09:38:05 INFO - Usage: mozinstall [options] installer 09:38:05 INFO - Options: 09:38:05 INFO - -h, --help show this help message and exit 09:38:05 INFO - -d DEST, --destination=DEST 09:38:05 INFO - Directory to install application into. [default: 09:38:05 INFO - "/builds/slave/test"] 09:38:05 INFO - --app=APP Application being installed. [default: firefox] 09:38:05 INFO - mkdir: /builds/slave/test/build/application 09:38:05 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'] 09:38:05 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 09:38:19 INFO - Reading from file tmpfile_stdout 09:38:19 INFO - Output received: 09:38:19 INFO - /builds/slave/test/build/application/firefox/firefox 09:38:19 INFO - Running post-action listener: _resource_record_post_action 09:38:19 INFO - [mozharness: 2016-09-19 16:38:19.599835Z] Finished install step (success) 09:38:19 INFO - [mozharness: 2016-09-19 16:38:19.600112Z] Running run-tests step. 09:38:19 INFO - Running pre-action listener: _resource_record_pre_action 09:38:19 INFO - Running main action method: run_tests 09:38:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:38:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:38:19 INFO - Python 2.7.3 09:38:19 INFO - Return code: 0 09:38:19 INFO - grabbing minidump binary from tooltool 09:38:19 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')]} 09:38:19 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 0x208d3b0>, '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 0x208e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:38:19 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 09:38:19 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 09:38:19 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 09:38:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:38:19 INFO - Return code: 0 09:38:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:38:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:38:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:38:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:38:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:38:19 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/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 09:38:19 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/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-028 --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 09:38:19 INFO - Using env: {'DISPLAY': ':0', 09:38:19 INFO - 'HOME': '/home/cltbld', 09:38:19 INFO - 'LANG': 'en_US.UTF-8', 09:38:19 INFO - 'LANGUAGE': 'en_US:en', 09:38:19 INFO - 'LOGNAME': 'cltbld', 09:38:19 INFO - 'MAIL': '/var/mail/cltbld', 09:38:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:38:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:19 INFO - 'MOZ_NO_REMOTE': '1', 09:38:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:38:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:19 INFO - 'NO_EM_RESTART': '1', 09:38:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:19 INFO - 'PWD': '/builds/slave/test', 09:38:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:38:19 INFO - 'SHELL': '/bin/bash', 09:38:19 INFO - 'SHLVL': '1', 09:38:19 INFO - 'TERM': 'linux', 09:38:19 INFO - 'TMOUT': '86400', 09:38:19 INFO - 'USER': 'cltbld', 09:38:19 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448', 09:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:19 INFO - '_': '/tools/buildbot/bin/python'} 09:38:19 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/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 09:38:19 INFO - mozversion application_buildid: 20160919064031 09:38:19 INFO - mozversion application_changeset: 01d67bfe6c8198416ec190cbc216d953c1625b94 09:38:19 INFO - mozversion application_display_name: Firefox 09:38:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:38:19 INFO - mozversion application_name: Firefox 09:38:19 INFO - mozversion application_remotingname: firefox 09:38:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:38:19 INFO - mozversion application_vendor: Mozilla 09:38:19 INFO - mozversion application_version: 50.0 09:38:19 INFO - mozversion platform_buildid: 20160919064031 09:38:19 INFO - mozversion platform_changeset: 01d67bfe6c8198416ec190cbc216d953c1625b94 09:38:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:38:19 INFO - mozversion platform_version: 50.0 09:38:19 INFO - using testdate: 1474303099 09:38:19 INFO - actual date: 1474303099 09:38:19 INFO - starting webserver on 'localhost:57166' 09:38:19 INFO - SUITE-START | Running 2 tests 09:38:19 INFO - TEST-START | dromaeo_css 09:38:19 INFO - operating with platform_type : linux_ 09:38:19 INFO - Initialising browser for dromaeo_css test... 09:38:19 INFO - TEST-INFO | started process 3869 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptK9Neh/profile http://localhost:57166/getInfo.html) 09:38:24 INFO - PROCESS | 3869 | __metrics Screen width/height:1600/1200 09:38:24 INFO - PROCESS | 3869 | colorDepth:24 09:38:24 INFO - PROCESS | 3869 | Browser inner width/height: 1024/697 09:38:24 INFO - PROCESS | 3869 | __metrics 09:38:25 INFO - PROCESS | 3869 | 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" 09:38:25 INFO - PROCESS | 3869 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:38:25 INFO - TEST-INFO | 3869: exit 0 09:38:25 INFO - Browser initialized. 09:38:25 INFO - Running cycle 1/1 for dromaeo_css test... 09:38:25 INFO - TEST-INFO | started process 3955 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptK9Neh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:37 INFO - PROCESS | 3955 | RSS: Main: 143437824 09:38:37 INFO - PROCESS | 3955 | 09:39:28 INFO - PROCESS | 3955 | 1474303168596 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] 09:41:37 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:57166/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57166/tests/dromaeo/cssquery-ext.html) 09:41:38 INFO - PROCESS | 3955 | RSS: Main: 266162176 09:41:38 INFO - PROCESS | 3955 | 09:44:41 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:57166/tests/dromaeo/cssquery-ext.html (next: http://localhost:57166/tests/dromaeo/cssquery-jquery.html) 09:44:41 INFO - PROCESS | 3955 | RSS: Main: 232173568 09:44:41 INFO - PROCESS | 3955 | 09:47:43 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:57166/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57166/tests/dromaeo/cssquery-mootools.html) 09:47:44 INFO - PROCESS | 3955 | RSS: Main: 177786880 09:47:44 INFO - PROCESS | 3955 | 09:50:46 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:57166/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57166/tests/dromaeo/cssquery-prototype.html) 09:50:46 INFO - PROCESS | 3955 | RSS: Main: 174960640 09:50:46 INFO - PROCESS | 3955 | 09:53:49 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:57166/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57166/tests/dromaeo/cssquery-yui.html) 09:53:49 INFO - PROCESS | 3955 | RSS: Main: 177061888 09:53:49 INFO - PROCESS | 3955 | 09:56:52 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:57166/tests/dromaeo/cssquery-yui.html (next: http://localhost:57166/tests/dromaeo/cssquery-dojo.html) 09:56:52 INFO - PROCESS | 3955 | RSS: Main: 266022912 09:56:52 INFO - PROCESS | 3955 | 09:56:52 INFO - PROCESS | 3955 | __start_tp_report 09:56:52 INFO - PROCESS | 3955 | _x_x_mozilla_page_load 09:56:52 INFO - PROCESS | 3955 | _x_x_mozilla_page_load_details 09:56:52 INFO - PROCESS | 3955 | |i|pagename|runs| 09:56:52 INFO - PROCESS | 3955 | |0;dojo.html;3255.3163835594523;3272.051105179498;3290.144562413772;3314.966850331496;3336.7831090979084;5654.802081927133;5722.856928576788;5735.311762588495;5735.369109397963;5749.741261643231;5626.521745651625;5631.549476041917;5676.3188417389865;5686.658800471964;5689.886202275952;5097.133487307166;5124.256982737501;5126.846194614158;5146.794128234867;5187.948120518803;5228.215767634847;5268.5521730652945;5273.683578985273;5284.2866213061325;5292.867678308035;5107.208382700687;5169.767360468769;5198.818041368553;5205.817796377128;5272.551833094191;5255.237990491396;5256.789728410839;5257.894842103175;5262.315898933114;5293.470652934714;5076.035553244908;5092.3125378073855;5151.793928242866;5152.690838549662;5167.664101833407;5546.611737178399;5555;5573.6098473106895;5576.804811831607;5609.663420194761;6055.182550355689;6068.939310606926;6070.939290607126;6072.696365181723;6076.240469941257;5929.762809487615;5994.100884867261;6005.969970150165;6072;6115.510759139258;5713.542916566665;5716.371199168088;5720;5725.656460612335;5739.885202295973;6211.378224295297;6242.937570624326;6247.9062814056915;6249.125122482856;6254.937450625435;5841.620294680833;5966.134910437962;6023;6024.186734790747;6103.328633850273;6155.507559395238;6156.476699480594;6166.599171053867;6170;6172.814815555541;6106.053561697944;6122.5408094392205;6127.693615319216;6134.35589263121;6138.478229350466;5873.3832947539895;5974.3428222895445;6027.517798576104;6041.5468839837185;6131.8160455185525;4642.698224615389;4659.6505262105475;4676.906461870743;4680.555347242007;4710.528947105262;4141.378793180987;4155.58444155582;4162.4796900387455;4177.352510360899;4186.832526698898;5726.570507211893;5820.039693450533;5876.206712093898;5888.823335299948;5950.166976623276;6149.784563273678;6190.3190649028575;6191.4118158774845;6196.783112591038;6197.721102550396;5905.261842269716;5907;5909.970450147722;5912.645241285537;5930.614510056833;5169.844904652867;5219.765110570033;5260.263563101169;5265.894682106336;5353.7590808413715;6324.778632747832;6341.207349081365;6368.77709280173;6380.617162970237;6382.234131904202;6107.6640784757265;6141.109539116893;6156.630602163885;6165.599236049642;6171.290301615192;6195;6214.5960512566535;6259.530535209969;6264.561480696308;6342.207224096988;5956.791512297049;6033.276006879202;6042.45617894374;6073.331933487401;6077.848053798513;6129.570930034854;6130.601510901777;6136.846578835565;6141.017437210025;6141.539384546266;5869.911951320819;5928.970355148197;5955.136505206634;5984.102384642339;5998.550108741774;5910.202122713551;5937.9406205937385;5940.019896716994;6010.248718910137;6030.185924900083;5929.466348028698;5932.2584676915385;5932.288125424977;5936.910946335722;5938;5843.532517398683;5953.613015154001;5969.253843269591;6000.729967151576;6027.939720602738;5748.338941021836;5771.3651498335985;5789.1605717171615;5801.6519008859605;5870.090136028923 09:56:52 INFO - PROCESS | 3955 | |1;ext.html;581.5522048023022;623.0311865049847;628.7924984755028;654.6628486329544;654.6955665615487;35421.64578354215;47260.763696181515;48293;48388.27417588739;48422.51577484224;49401;49439.752801236034;49627.25559116616;49632.255516167286;49793.25310120351;8586.656533738644;9305.302102342317;9459.574319155654;9467.668631597895;11098;11055.11559075272;11436.2566433182;11455.770884582304;11459.541618335257;11464.770704585902;11438.656840294805;11456.48445819936;11471.426428678533;11478.540858365655;11488.36813975231;11335.036521895649;11399.715007124805;11400.600978965696;11408.315501069921;11409.942950285278;11227.60703375386;11261.493232804543;11278.548858045668;11280;11283.37941413222;46152.53847461516;46240.76879615614;46458.53541464576;46494.53505464936;46645.533544664795;3748.775073495599;3915;4125.50493940729;4183.874483765492;4588.564086411786;35236.47145292823;35424.822875885715;35556.46665300023;37328.066798330256;37369.25261494782;4709.952900470986;4726.456457507396;4802.375691160127;4807.519248075199;4853.538913803183;418751;421568;423647;424596;425509;68626.313736863;69203.30796692069;69254.30745692579;69289;69320;40597.79701101476;41335.586644133175;41471.585284147375;43674.34488482675;43776.343354849705;19854.50363740918;20002.19991200365;20021.198092171482;20026.499337516678;20032.69950950737;13160.14459060158;14093;14699;15039.624009399853;15124.924375378272;21155.365339039854;21213;21393.93030348476;21446.78553214479;21651.13395464195;7462.925370746331;7632.122305934889;7635.465517413728;7658.234176582298;7703.614819259014;1025.4923812712755;1075.93544387337;1104.7735214281054;1148.064327573042;1148.5757660501085;34486.82756586201;35833.1041723959;35999;36036.09909752277;36223.63776362203;1135.6422726841183;1158.4207896051973;1183.0476466444422;1203.3141109567637;1235.3390935849457;18601.348952786586;18664;18672;18745.156467958703;18908.527286817938;626814;634887;635663;640416;649004;65963.34036659572;66316;66353.33646663568;66381;66531.33468665251;1315.7565850317721;1422.3686180566076;1464.1288433382333;1467.8935727231244;1476.468471350313;33794.66205337915;35916.28167436689;36463.27073458569;36634.8168259157;36656.26687466289;55597.722011389684;56138.15792763193;56283.437165627816;56284.71857640849;56372;441223;442817;442905;444368;444907;28140.85929570339;30375.240618984702;30993.225169370948;31170.68829311678;31875.20311992219;52559;52582.73708631432;52642.73678631582;53390.19914701205;54019.18971215514;43917.560824391345;44560.77719611381;46319;46870.76564617155;47217.527824721314;44328;46047.539524604326;46140;46705.2994205094;46746.53253467422;45060;45617;46147.53852461433;46148.76925615351;46207;36863.26273474462;39839.80080099581;40782;40812.91870812895;41192.79403602963;29942.25144371408;29961.889752853935;30352.84823575868;30395.392092157592;30781.69218307878 09:56:52 INFO - PROCESS | 3955 | |2;jquery.html;416.7145505328848;420.8379773787093;425.76157351882955;434.2660903073805;434.6045098959946;19357.612847743036;20466.181352745873;20991.68512472312;21377.893110534464;21581.24465643703;20178.293759718414;20274;20712.171513139496;20836.791632083674;20987.265445709367;5812.651240925547;6042.365551617061;6098.298695649991;6112.69436528174;6119.296280927706;5793.217915581385;5919.822405327847;5942.197803296564;5946.43508866657;6002.819915402545;5891.793787217448;5895.941040589582;5897.675627840467;5907.645541267538;5913.615614985012;5754.223179870727;5955.106733989893;5970.641761494303;5988.281406231258;5990.191324171225;5281.9207711884355;5428.511433970962;5429.267048948381;5432.022235997519;5437.075697131497;13880.51418200358;14145.363458644386;14345.569632911029;14387;14581.416743330254;2410.68661074061;2423.285130886393;2546.5670835957744;2840.900568480093;2862.041216192578;11132.220744547885;11183;11230.101591872712;15108.093514389171;15361.539153825403;1982.3557343863158;2005.929792457257;2075.2529089527757;2120.8833514156713;2122.352682431858;89863.99999999935;90041;91316.54341728316;93256;95317;11849.466773995191;11964.940175299242;11969.521219151136;12052.276863388224;12299.815502767466;17109.315627375014;17177.82822171787;17214.483565493054;17221.655566888592;17233;8563.871541926876;8868;8946.105389461132;8998.640054397882;9236.122568355995;8951.149640784115;8980.101989800967;8991.910080899239;9125.589348479334;9245.676401325922;4781.354517140211;4803.519648035169;4819.228923372244;4822.590079843252;4926.778294976734;2109.7995690409693;2133.775953524889;2152.5264441822774;2157.8381621378458;2235.7764223577838;961.7018724195382;971.3491960386558;1032.9896701033044;1046.8796088449926;1079.3577821196375;20924.58150836975;21332.253371132247;21396.465088372603;21577.352679419644;21647.67528487074;1009.6213919780082;1045.8588090607823;1046.874375074996;1052.752603138262;1055.3667799320347;11365.545378184945;11766.941165294118;11916.582919597773;11977.700557486132;12084.637460876187;558759;559342;565817;566820;569275;64986.67506662531;67857.3214267851;96357;98433.50783246181;100933.49533252287;995.4077323992213;1000.3697670467559;1017.7353887989176;1030.927835051553;1032.912202462784;18143.092845357416;18200.45398638043;18721.25114995385;18963.33628323002;18978.24087036503;45125.54874451213;45474.31788523109;45476.54523454723;45757.54242457533;46215.30677039914;61559;61800.381996179465;62513.37486625076;63201.683991579746;64888;7890.092639346549;7890.250426209501;8175.550344731097;8294.377921656021;8323.209295116954;13055.34723263814;13152.736945261233;13248.602541923758;13659.453621855015;14006.229657368942;11070.723231919266;11869.406529673655;11950.462229199533;11975.161738678209;12013.879861201625;11276.774464510829;11282;11329.206955513035;11571.479283432434;11915.16593838423;6595.439387651912;9745.463999480096;11998.700032499257;12442.564510242099;12622.55821046259;8802.383833131875;8922.330825188006;8940.374173807772;8994.190522852974;9005.549722513979;11513.942430287794;12019.4591243396;12090.879091209326;12160.574379896661;12851.293178874877 09:56:52 INFO - PROCESS | 3955 | |3;mootools.html;1439.726451974124;1564.4446221591336;1572.866306363959;1720.320473413002;1851.4168037068323;9174.90825091749;9528.85706714399;9561.474118923456;9843.409395436262;9849.803003939916;9655.565499552536;9738;9740.172085372751;9750.756231094227;9759.804803903917;2527.3428908483834;2573.4853029394103;2574.3306740247576;2576.8582727949956;2578.5358635445614;2470.752924707524;2532.3289328327915;2537.1246919812634;2538.200466852945;2539.2889990802605;2482.6648402465617;2487.2911220302217;2598.4803039392104;2601.6617839680816;2602.1152808045263;2453.705555333371;2474.7772700456953;2476;2480.6403071554705;2487.117073438921;2439.9512009759883;2441.8901149448316;2457.496213276274;2459.7417271186628;2487.601983682602;9722.319437639368;9744.951275243578;9797.608095676165;9818.067283608047;9829.164521015722;5666.631668941505;5857.238558987347;5857.824265272006;5859.355470898197;5864.237649105589;3621.5110960020324;3621.692156166729;3700.2414505026404;3707.2400157967554;3722.9441558376648;1216.9756604867941;1234.5061975209899;1238.777020136375;1251.393074358935;1265.1839563481594;13657.317134143253;13734.725305493934;13745.450181992708;13745.793813092812;13792.103513271706;4794.856154315376;4855.635827312895;4938.111139994836;4982.053409852189;5020.7740651670765;10763;10783.56865725378;10791.082757965503;10791.406472643923;10800.351978881292;5735.254416925773;5742.28221472316;5744.195812586241;5752.913706294409;5755.597108202385;4735.195016847144;4877.146499362669;5327.893442131136;5403.729813509309;5423.322084739408;4342.305231163062;4352.73883566987;4399.12017596482;4423.115376924564;4436.112777444524;1278.8081787731915;1295.4105881823748;1297.6107167849607;1302.4855182203166;1305.5039085147582;856.7472595584318;986.40815510693;1012.448215722433;1056.434807378049;1077.4343469678324;5999.5500337473995;6172.506199504029;6255.43701066897;6261.405166509175;6518.380753828379;1018.9592416303265;1050.0706874416196;1069.8127827630133;1072.463768115942;1083.0793825248477;4195.8741237762915;4356.869293921188;4482.932756008662;4584.014436896084;4597.747123908217;12068.517259309705;12518.123731338901;12530.185537940004;12542.933850622616;12580.182288151242;12137.69655758613;12257;12377.938110309571;12395.876041239653;12435.129540932048;9065.954670226607;9078.636854525877;9182.219511341476;9241.491717955443;9297;6399.616023038632;6456.515761317826;6541.70562324706;6573.244076931214;6644.867102657823;10549.630762923261;10688.57245710194;10777.568897244022;11064.446777661246;11218.439078045902;10198.694039178836;10885.619003334845;11083.72290692739;11090.667279981613;11132.608423947007;8547;8774.38579299443;8856.468611883307;8864.42381245217;8873.378863479495;9476.194523465583;9621.037896210602;9628.71113866585;9631.229501639993;9632.27757918145;8396.454230475243;8430.494170349799;8433.28317093054;8632.870506942276;8965.955170224108;8129.430939834155;8301.377396695392;8305.418620696493;8434.156584341517;8447.577621118797;7510.812229694301;7667.808304792425;7699;7775.455718099905;7777;5434.891302174014;5470.890582188414;5550.69471179089;5615.466530679579;5624.915626265692;5662.207290979184;5666.263385759825;5680.545556355399;5813.215215945962;5819.185314056121 09:56:52 INFO - PROCESS | 3955 | |4;prototype.html;344.7052769881751;345.3162737779197;348.08627353197863;351.0714161044037;353.63045617329885;5603.215549823028;5633.098704207323;5644.125160600113;5646.18130371096;5670.319561652597;5500.80747173849;5513.448655134484;5586.469285417878;5590.91613625796;5607.158926161078;3911.8239679214503;4168.4581004469455;4276.358546218061;4321.654267658596;4326.199653064177;4281.529031806499;4291.35629655551;4297.785110744466;4302.741835489865;4323.891902702442;4306.18182545317;4308.728924967142;4318.935215971763;4321.891952701176;4322.222000039991;4087.4686290782306;4257.787110644455;4290.69965102443;4324.97837510812;4356.3683265926265;3768.7186356638877;3772.264408440355;3786.753860999054;3796.468494410785;3806.600306967784;5359.464053594648;5361.731913404353;5367.43641917597;5375.865603359908;5386.407495175527;4827.975860120712;4842.564169224752;4851.919031415042;4903.828366007173;4909.754512274372;5648.6893220872835;5687.601867869251;5712.285964254468;5715.371309155989;5725.227094342252;4138.482689663792;4162.396452514398;4165.770882601456;4172.728772629769;4174.6621891740415;5753.568482363798;5925.31858836235;5956.195913551702;5978.701064946778;6043.335233124353;4120.278951183531;4167.416561681306;4168.499780026416;4173.707840451139;4174.499060112746;4223.873283801491;4225.535191128974;4232;4241.681873859411;4275.8075886585175;4700.55344742249;4875.34182885313;4895.583875370633;4905.7301848397965;4910.508949105132;5728.427157284238;5737.3361725100085;5764.13537969308;5770.3652598215;5846.386129456431;1471.8528147185195;1493.7759336099673;1497.6595946627708;1497.827749808764;1501.5345242974495;928.7601052594727;953.4708236928433;955.7276176289863;963.2486660404895;977.3256453047517;4987.351644286292;5026.648134630541;5030.673006254582;5032.672876263105;5055.519725626134;5196.181336532183;5203.843884683465;5233.215017747369;5304.443033481506;5310.7610157543;5030.974845125751;5061.569766569809;5070.847874563769;5306.734663266821;5728.312602487728;3166.1293144385477;3186.3627274545183;3190.8512935343256;3193.1538142392283;3198.2164369729567;6045.274567051982;6232.470240029557;6246.500279977591;6266.874662506724;6273.341299163613;5816.447437493432;6070;6084.361142080106;6110.725017374229;6131.417515336036;6056.909146362808;6101.871153836554;6126.713390188022;6138.846528836815;6176.166217560662;5934.457041169241;5958.344582096052;5966.880662386815;5972.432618901197;5988.8802223956145;5784.739686714024;5826.94173058264;5847.473727364388;5871.2073870027;5873.500752436091;6165;6167.84580385494;6177.733564619152;6193.690315484298;6202.054024338403;4069.2675318442966;4286.271333873186;4292.7424354538825;4295.806688699078;4312.654987601047;5880.558958078076;5908.438698323653;5908.615939963888;5922.763089476372;5926.555508336978;5795.478406943395;5800.970995144998;5803.216565763737;5815.41845815415;5831.825045248649;5638.464345887134;5736.42635736423;5739.311282646109;5769.798057067983;5820.4390068369785;5732.254806875079;5738.741756620879;5746.798862039808;5760.711964401847;5778.971105144447;5589.580781441489;5769.567282453917;5816.214811000461;5824.534037276923;5831.241938547962;5625.296837895263;5641.605087643826;5656.123300888369;5661.490465858093;5674.602777805679 09:56:52 INFO - PROCESS | 3955 | |5;yui.html;777.8133248020475;794.7139029949219;826.2976470000505;826.3843436639704;927.9536023198839;15693.52919412418;16080.11559364236;16468.67062658746;16582.08798516081;16680.74978875318;14407.495737649184;14515.201663908481;14516.927415362934;14537.345819438124;14537.418503259856;753.5403403923602;861.4013260783757;890.3500444675375;904.5115637555712;910.5856835139996;898.9865152022725;911.0525053943891;912.2519533982137;912.4616476278994;912.7626817027588;954.3987288008545;955.4028732042474;957.3872721458272;957.6983250276176;957.760559860035;950.0262231212993;952.8618350339162;953.3421938862198;953.3803028031765;953.8092381523688;699.6117154978984;699.919509256437;699.9615021173834;700.5376451542008;700.6321681117403;14757.409703611842;14766.852331476763;14776.670099691079;14783.556493305217;14802.111873287637;16268.26792794327;16703.074777427293;16756.245968931427;16813.663726725397;17397.826021739747;13438.798418023738;13469.326533673277;13506.392212350467;13684.384202710902;13763.724725505434;4086.8978275543054;4182.665386769051;4184;4200.894977625553;4264.765437900915;85423.71864422038;90642.5467872663;90819.54590227072;92593;92650;12539.37303134858;12638.810417843739;12685.873141268468;12825.807612885814;12846.87970187033;23772.405689857893;23827.642585361235;23936.640950385758;23973.28080157598;24042.39894002629;8242.505449673039;8370.623321950527;8376.181540345346;8399.370047246359;8444.282236010007;4790.8323208688225;4972.627052971042;5051.494850514919;5065.366829146356;5148;3845.73079884411;3988.082740969593;3991.2815693174925;3992.6207010333974;3992.941870404345;628.739073284591;635.3614617309574;641.4739913271073;641.7048157847349;642.1491523730982;709.0888208651961;711.4965740001087;728.9890651640229;749.7713197474823;751.4777229825219;9515.714528564155;9728.95135524332;9733.318667693193;9818.607255709834;9889.20886329092;621.1862460177182;658.4403257231411;701.225146213437;731.9414446844239;735.6285076036568;4664.836730714476;4854.951450485521;4865.099956508057;4871.732054736953;4941.209406494943;120710;121021;122447.38776306061;122893;125059.3747031259;105615.41576876353;106660;107119;107760.46119769508;110391.34412983812;90344.54827725951;91340.99999999868;94607;96403;97225;8896.644134234559;9263.212626926786;9370.718878433658;9688.5640146195;9778.853317200106;50412;51794.78462584514;52322.73838630782;52691;55264.44735552593;74645;76622;76843;77083;78919;12908.483660653468;13445.798313025116;13523.932380338036;13645.727085458433;13895.235762033186;13962.301884905512;14189.361478733275;14211.147331160164;14770.556883293519;15408.537743867702;12484;12582.127963424955;12669.93665031669;13155.779245744521;13353.265570393722;13122.540711075448;13326.267055312052;13421.932890335485;13514.391852366864;13583.728325433634;12125.69685757863;12380.257184568954;12898.48406063747;13091.934540327238;13355.399007044512;10542.314749541256;10666.679999600025;10955.945220273848;10957.506912188812;11202.38386888729;10638.468076596295;10731.248812583357;10772.353658780497;10868.61959831402;11031.83452248201 09:56:52 INFO - PROCESS | 3955 | __end_tp_report 09:56:52 INFO - PROCESS | 3955 | __start_cc_report 09:56:52 INFO - PROCESS | 3955 | _x_x_mozilla_cycle_collect,1054 09:56:52 INFO - PROCESS | 3955 | __end_cc_report 09:56:52 INFO - PROCESS | 3955 | __startTimestamp1474304212381__endTimestamp 09:56:52 INFO - PROCESS | 3955 | 09:56:52 INFO - PROCESS | 3955 | ------- Summary: start ------- 09:56:52 INFO - PROCESS | 3955 | Number of tests: 6 09:56:52 INFO - PROCESS | 3955 | 09:56:52 INFO - PROCESS | 3955 | [#0] dojo.html Cycles:165 Average:5671.74 Median:5912.65 stddev:645.08 (10.9%) stddev-sans-first:618.58 09:56:52 INFO - PROCESS | 3955 | Values: 3255.3 3272.1 3290.1 3315.0 3336.8 5654.8 5722.9 5735.3 5735.4 5749.7 5626.5 5631.5 5676.3 5686.7 5689.9 5097.1 5124.3 5126.8 5146.8 5187.9 5228.2 5268.6 5273.7 5284.3 5292.9 5107.2 5169.8 5198.8 5205.8 5272.6 5255.2 5256.8 5257.9 5262.3 5293.5 5076.0 5092.3 5151.8 5152.7 5167.7 5546.6 5555.0 5573.6 5576.8 5609.7 6055.2 6068.9 6070.9 6072.7 6076.2 5929.8 5994.1 6006.0 6072.0 6115.5 5713.5 5716.4 5720.0 5725.7 5739.9 6211.4 6242.9 6247.9 6249.1 6254.9 5841.6 5966.1 6023.0 6024.2 6103.3 6155.5 6156.5 6166.6 6170.0 6172.8 6106.1 6122.5 6127.7 6134.4 6138.5 5873.4 5974.3 6027.5 6041.5 6131.8 4642.7 4659.7 4676.9 4680.6 4710.5 4141.4 4155.6 4162.5 4177.4 4186.8 5726.6 5820.0 5876.2 5888.8 5950.2 6149.8 6190.3 6191.4 6196.8 6197.7 5905.3 5907.0 5910.0 5912.6 5930.6 5169.8 5219.8 5260.3 5265.9 5353.8 6324.8 6341.2 6368.8 6380.6 6382.2 6107.7 6141.1 6156.6 6165.6 6171.3 6195.0 6214.6 6259.5 6264.6 6342.2 5956.8 6033.3 6042.5 6073.3 6077.8 6129.6 6130.6 6136.8 6141.0 6141.5 5869.9 5929.0 5955.1 5984.1 5998.6 5910.2 5937.9 5940.0 6010.2 6030.2 5929.5 5932.3 5932.3 5936.9 5938.0 5843.5 5953.6 5969.3 6000.7 6027.9 5748.3 5771.4 5789.2 5801.7 5870.1 09:56:52 INFO - PROCESS | 3955 | 09:56:52 INFO - PROCESS | 3955 | [#1] ext.html Cycles:180 Average:67451.98 Median:22730.55 stddev:135482.44 (596.0%) stddev-sans-first:135769.47 09:56:52 INFO - PROCESS | 3955 | Values: 581.6 623.0 628.8 654.7 654.7 35421.6 47260.8 48293.0 48388.3 48422.5 49401.0 49439.8 49627.3 49632.3 49793.3 8586.7 9305.3 9459.6 9467.7 11098.0 11055.1 11436.3 11455.8 11459.5 11464.8 11438.7 11456.5 11471.4 11478.5 11488.4 11335.0 11399.7 11400.6 11408.3 11409.9 11227.6 11261.5 11278.5 11280.0 11283.4 46152.5 46240.8 46458.5 46494.5 46645.5 3748.8 3915.0 4125.5 4183.9 4588.6 35236.5 35424.8 35556.5 37328.1 37369.3 4710.0 4726.5 4802.4 4807.5 4853.5 418751.0 421568.0 423647.0 424596.0 425509.0 68626.3 69203.3 69254.3 69289.0 69320.0 40597.8 41335.6 41471.6 43674.3 43776.3 19854.5 20002.2 20021.2 20026.5 20032.7 13160.1 14093.0 14699.0 15039.6 15124.9 21155.4 21213.0 21393.9 21446.8 21651.1 7462.9 7632.1 7635.5 7658.2 7703.6 1025.5 1075.9 1104.8 1148.1 1148.6 34486.8 35833.1 35999.0 36036.1 36223.6 1135.6 1158.4 1183.0 1203.3 1235.3 18601.3 18664.0 18672.0 18745.2 18908.5 626814.0 634887.0 635663.0 640416.0 649004.0 65963.3 66316.0 66353.3 66381.0 66531.3 1315.8 1422.4 1464.1 1467.9 1476.5 33794.7 35916.3 36463.3 36634.8 36656.3 55597.7 56138.2 56283.4 56284.7 56372.0 441223.0 442817.0 442905.0 444368.0 444907.0 28140.9 30375.2 30993.2 31170.7 31875.2 52559.0 52582.7 52642.7 53390.2 54019.2 43917.6 44560.8 46319.0 46870.8 47217.5 44328.0 46047.5 46140.0 46705.3 46746.5 45060.0 45617.0 46147.5 46148.8 46207.0 36863.3 39839.8 40782.0 40812.9 41192.8 29942.3 29961.9 30352.8 30395.4 30781.7 09:56:52 INFO - PROCESS | 3955 | 09:56:52 INFO - PROCESS | 3955 | [#2] jquery.html Cycles:180 Average:31622.03 Median:2323.23 stddev:92761.80 (3992.8%) stddev-sans-first:92992.42 09:56:52 INFO - PROCESS | 3955 | Values: 416.7 420.8 425.8 434.3 434.6 19357.6 20466.2 20991.7 21377.9 21581.2 20178.3 20274.0 20712.2 20836.8 20987.3 5812.7 6042.4 6098.3 6112.7 6119.3 5793.2 5919.8 5942.2 5946.4 6002.8 5891.8 5895.9 5897.7 5907.6 5913.6 5754.2 5955.1 5970.6 5988.3 5990.2 5281.9 5428.5 5429.3 5432.0 5437.1 13880.5 14145.4 14345.6 14387.0 14581.4 2410.7 2423.3 2546.6 2840.9 2862.0 11132.2 11183.0 11230.1 15108.1 15361.5 1982.4 2005.9 2075.3 2120.9 2122.4 89864.0 90041.0 91316.5 93256.0 95317.0 11849.5 11964.9 11969.5 12052.3 12299.8 17109.3 17177.8 17214.5 17221.7 17233.0 8563.9 8868.0 8946.1 8998.6 9236.1 8951.1 8980.1 8991.9 9125.6 9245.7 4781.4 4803.5 4819.2 4822.6 4926.8 2109.8 2133.8 2152.5 2157.8 2235.8 961.7 971.3 1033.0 1046.9 1079.4 20924.6 21332.3 21396.5 21577.4 21647.7 1009.6 1045.9 1046.9 1052.8 1055.4 11365.5 11766.9 11916.6 11977.7 12084.6 558759.0 559342.0 565817.0 566820.0 569275.0 64986.7 67857.3 96357.0 98433.5 100933.5 995.4 1000.4 1017.7 1030.9 1032.9 18143.1 18200.5 18721.3 18963.3 18978.2 45125.5 45474.3 45476.5 45757.5 46215.3 61559.0 61800.4 62513.4 63201.7 64888.0 7890.1 7890.3 8175.6 8294.4 8323.2 13055.3 13152.7 13248.6 13659.5 14006.2 11070.7 11869.4 11950.5 11975.2 12013.9 11276.8 11282.0 11329.2 11571.5 11915.2 6595.4 9745.5 11998.7 12442.6 12622.6 8802.4 8922.3 8940.4 8994.2 9005.5 11513.9 12019.5 12090.9 12160.6 12851.3 09:56:52 INFO - PROCESS | 3955 | 09:56:52 INFO - PROCESS | 3955 | [#3] mootools.html Cycles:180 Average:6367.94 Median:4533.47 stddev:3666.18 (80.9%) stddev-sans-first:3657.76 09:56:52 INFO - PROCESS | 3955 | Values: 1439.7 1564.4 1572.9 1720.3 1851.4 9174.9 9528.9 9561.5 9843.4 9849.8 9655.6 9738.0 9740.2 9750.8 9759.8 2527.3 2573.5 2574.3 2576.9 2578.5 2470.8 2532.3 2537.1 2538.2 2539.3 2482.7 2487.3 2598.5 2601.7 2602.1 2453.7 2474.8 2476.0 2480.6 2487.1 2440.0 2441.9 2457.5 2459.7 2487.6 9722.3 9745.0 9797.6 9818.1 9829.2 5666.6 5857.2 5857.8 5859.4 5864.2 3621.5 3621.7 3700.2 3707.2 3722.9 1217.0 1234.5 1238.8 1251.4 1265.2 13657.3 13734.7 13745.5 13745.8 13792.1 4794.9 4855.6 4938.1 4982.1 5020.8 10763.0 10783.6 10791.1 10791.4 10800.4 5735.3 5742.3 5744.2 5752.9 5755.6 4735.2 4877.1 5327.9 5403.7 5423.3 4342.3 4352.7 4399.1 4423.1 4436.1 1278.8 1295.4 1297.6 1302.5 1305.5 856.7 986.4 1012.4 1056.4 1077.4 5999.6 6172.5 6255.4 6261.4 6518.4 1019.0 1050.1 1069.8 1072.5 1083.1 4195.9 4356.9 4482.9 4584.0 4597.7 12068.5 12518.1 12530.2 12542.9 12580.2 12137.7 12257.0 12377.9 12395.9 12435.1 9066.0 9078.6 9182.2 9241.5 9297.0 6399.6 6456.5 6541.7 6573.2 6644.9 10549.6 10688.6 10777.6 11064.4 11218.4 10198.7 10885.6 11083.7 11090.7 11132.6 8547.0 8774.4 8856.5 8864.4 8873.4 9476.2 9621.0 9628.7 9631.2 9632.3 8396.5 8430.5 8433.3 8632.9 8966.0 8129.4 8301.4 8305.4 8434.2 8447.6 7510.8 7667.8 7699.0 7775.5 7777.0 5434.9 5470.9 5550.7 5615.5 5624.9 5662.2 5666.3 5680.5 5813.2 5819.2 09:56:52 INFO - PROCESS | 3955 | 09:56:52 INFO - PROCESS | 3955 | [#4] prototype.html Cycles:180 Average:4829.55 Median:5549.96 stddev:1420.94 (25.6%) stddev-sans-first:1384.48 09:56:52 INFO - PROCESS | 3955 | Values: 344.7 345.3 348.1 351.1 353.6 5603.2 5633.1 5644.1 5646.2 5670.3 5500.8 5513.4 5586.5 5590.9 5607.2 3911.8 4168.5 4276.4 4321.7 4326.2 4281.5 4291.4 4297.8 4302.7 4323.9 4306.2 4308.7 4318.9 4321.9 4322.2 4087.5 4257.8 4290.7 4325.0 4356.4 3768.7 3772.3 3786.8 3796.5 3806.6 5359.5 5361.7 5367.4 5375.9 5386.4 4828.0 4842.6 4851.9 4903.8 4909.8 5648.7 5687.6 5712.3 5715.4 5725.2 4138.5 4162.4 4165.8 4172.7 4174.7 5753.6 5925.3 5956.2 5978.7 6043.3 4120.3 4167.4 4168.5 4173.7 4174.5 4223.9 4225.5 4232.0 4241.7 4275.8 4700.6 4875.3 4895.6 4905.7 4910.5 5728.4 5737.3 5764.1 5770.4 5846.4 1471.9 1493.8 1497.7 1497.8 1501.5 928.8 953.5 955.7 963.2 977.3 4987.4 5026.6 5030.7 5032.7 5055.5 5196.2 5203.8 5233.2 5304.4 5310.8 5031.0 5061.6 5070.8 5306.7 5728.3 3166.1 3186.4 3190.9 3193.2 3198.2 6045.3 6232.5 6246.5 6266.9 6273.3 5816.4 6070.0 6084.4 6110.7 6131.4 6056.9 6101.9 6126.7 6138.8 6176.2 5934.5 5958.3 5966.9 5972.4 5988.9 5784.7 5826.9 5847.5 5871.2 5873.5 6165.0 6167.8 6177.7 6193.7 6202.1 4069.3 4286.3 4292.7 4295.8 4312.7 5880.6 5908.4 5908.6 5922.8 5926.6 5795.5 5801.0 5803.2 5815.4 5831.8 5638.5 5736.4 5739.3 5769.8 5820.4 5732.3 5738.7 5746.8 5760.7 5779.0 5589.6 5769.6 5816.2 5824.5 5831.2 5625.3 5641.6 5656.1 5661.5 5674.6 09:56:52 INFO - PROCESS | 3955 | 09:56:52 INFO - PROCESS | 3955 | [#5] yui.html Cycles:180 Average:22157.47 Median:4727.83 stddev:32551.53 (688.5%) stddev-sans-first:32603.26 09:56:52 INFO - PROCESS | 3955 | Values: 777.8 794.7 826.3 826.4 928.0 15693.5 16080.1 16468.7 16582.1 16680.7 14407.5 14515.2 14516.9 14537.3 14537.4 753.5 861.4 890.4 904.5 910.6 899.0 911.1 912.3 912.5 912.8 954.4 955.4 957.4 957.7 957.8 950.0 952.9 953.3 953.4 953.8 699.6 699.9 700.0 700.5 700.6 14757.4 14766.9 14776.7 14783.6 14802.1 16268.3 16703.1 16756.2 16813.7 17397.8 13438.8 13469.3 13506.4 13684.4 13763.7 4086.9 4182.7 4184.0 4200.9 4264.8 85423.7 90642.5 90819.5 92593.0 92650.0 12539.4 12638.8 12685.9 12825.8 12846.9 23772.4 23827.6 23936.6 23973.3 24042.4 8242.5 8370.6 8376.2 8399.4 8444.3 4790.8 4972.6 5051.5 5065.4 5148.0 3845.7 3988.1 3991.3 3992.6 3992.9 628.7 635.4 641.5 641.7 642.1 709.1 711.5 729.0 749.8 751.5 9515.7 9729.0 9733.3 9818.6 9889.2 621.2 658.4 701.2 731.9 735.6 4664.8 4855.0 4865.1 4871.7 4941.2 120710.0 121021.0 122447.4 122893.0 125059.4 105615.4 106660.0 107119.0 107760.5 110391.3 90344.5 91341.0 94607.0 96403.0 97225.0 8896.6 9263.2 9370.7 9688.6 9778.9 50412.0 51794.8 52322.7 52691.0 55264.4 74645.0 76622.0 76843.0 77083.0 78919.0 12908.5 13445.8 13523.9 13645.7 13895.2 13962.3 14189.4 14211.1 14770.6 15408.5 12484.0 12582.1 12669.9 13155.8 13353.3 13122.5 13326.3 13421.9 13514.4 13583.7 12125.7 12380.3 12898.5 13091.9 13355.4 10542.3 10666.7 10955.9 10957.5 11202.4 10638.5 10731.2 10772.4 10868.6 11031.8 09:56:52 INFO - PROCESS | 3955 | -------- Summary: end -------- 09:56:52 INFO - PROCESS | 3955 | 09:56:53 INFO - TEST-INFO | 3955: exit 0 09:56:53 INFO - TEST-OK | dromaeo_css | took 1113291ms 09:56:53 INFO - TEST-START | kraken 09:56:53 INFO - operating with platform_type : linux_ 09:56:53 INFO - Initialising browser for kraken test... 09:56:53 INFO - TEST-INFO | started process 4150 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdoTYlz/profile http://localhost:57166/getInfo.html) 09:56:57 INFO - PROCESS | 4150 | __metrics Screen width/height:1600/1200 09:56:57 INFO - PROCESS | 4150 | colorDepth:24 09:56:57 INFO - PROCESS | 4150 | Browser inner width/height: 1024/697 09:56:57 INFO - PROCESS | 4150 | __metrics 09:56:58 INFO - PROCESS | 4150 | 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" 09:56:58 INFO - PROCESS | 4150 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:56:59 INFO - TEST-INFO | 4150: exit 0 09:56:59 INFO - Browser initialized. 09:56:59 INFO - Running cycle 1/1 for kraken test... 09:56:59 INFO - TEST-INFO | started process 4240 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdoTYlz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:10 INFO - PROCESS | 4240 | RSS: Main: 143958016 09:57:10 INFO - PROCESS | 4240 | 09:57:13 INFO - PROCESS | 4240 | Cycle 1(1): loaded http://localhost:57166/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57166/tests/kraken/driver.html?testName=audio-beat-detection) 09:57:14 INFO - PROCESS | 4240 | RSS: Main: 198918144 09:57:14 INFO - PROCESS | 4240 | 09:57:16 INFO - PROCESS | 4240 | Cycle 1(1): loaded http://localhost:57166/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57166/tests/kraken/driver.html?testName=audio-dft) 09:57:17 INFO - PROCESS | 4240 | RSS: Main: 183504896 09:57:17 INFO - PROCESS | 4240 | 09:57:20 INFO - PROCESS | 4240 | Cycle 1(1): loaded http://localhost:57166/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57166/tests/kraken/driver.html?testName=audio-fft) 09:57:20 INFO - PROCESS | 4240 | RSS: Main: 274444288 09:57:20 INFO - PROCESS | 4240 | 09:57:22 INFO - PROCESS | 4240 | Cycle 1(1): loaded http://localhost:57166/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57166/tests/kraken/driver.html?testName=audio-oscillator) 09:57:23 INFO - PROCESS | 4240 | RSS: Main: 179499008 09:57:23 INFO - PROCESS | 4240 | 09:57:25 INFO - PROCESS | 4240 | Cycle 1(1): loaded http://localhost:57166/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57166/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:57:26 INFO - PROCESS | 4240 | RSS: Main: 178950144 09:57:26 INFO - PROCESS | 4240 | 09:57:29 INFO - PROCESS | 4240 | Cycle 1(1): loaded http://localhost:57166/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57166/tests/kraken/driver.html?testName=imaging-darkroom) 09:57:29 INFO - PROCESS | 4240 | RSS: Main: 214749184 09:57:29 INFO - PROCESS | 4240 | 09:57:33 INFO - PROCESS | 4240 | Cycle 1(1): loaded http://localhost:57166/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57166/tests/kraken/driver.html?testName=imaging-desaturate) 09:57:33 INFO - PROCESS | 4240 | RSS: Main: 213602304 09:57:33 INFO - PROCESS | 4240 | 09:57:36 INFO - PROCESS | 4240 | Cycle 1(1): loaded http://localhost:57166/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57166/tests/kraken/driver.html?testName=json-parse-financial) 09:57:37 INFO - PROCESS | 4240 | RSS: Main: 214007808 09:57:37 INFO - PROCESS | 4240 | 09:57:38 INFO - PROCESS | 4240 | Cycle 1(1): loaded http://localhost:57166/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57166/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:57:39 INFO - PROCESS | 4240 | RSS: Main: 176701440 09:57:39 INFO - PROCESS | 4240 | 09:57:40 INFO - PROCESS | 4240 | Cycle 1(1): loaded http://localhost:57166/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57166/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:57:41 INFO - PROCESS | 4240 | RSS: Main: 183103488 09:57:41 INFO - PROCESS | 4240 | 09:57:43 INFO - PROCESS | 4240 | Cycle 1(1): loaded http://localhost:57166/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57166/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:57:44 INFO - PROCESS | 4240 | RSS: Main: 185147392 09:57:44 INFO - PROCESS | 4240 | 09:57:46 INFO - PROCESS | 4240 | Cycle 1(1): loaded http://localhost:57166/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57166/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:57:47 INFO - PROCESS | 4240 | RSS: Main: 185192448 09:57:47 INFO - PROCESS | 4240 | 09:57:49 INFO - PROCESS | 4240 | Cycle 1(1): loaded http://localhost:57166/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57166/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:57:50 INFO - PROCESS | 4240 | RSS: Main: 183549952 09:57:50 INFO - PROCESS | 4240 | 09:57:51 INFO - PROCESS | 4240 | Cycle 1(1): loaded http://localhost:57166/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57166/tests/kraken/driver.html?testName=ai-astar) 09:57:52 INFO - PROCESS | 4240 | RSS: Main: 234278912 09:57:52 INFO - PROCESS | 4240 | 09:57:52 INFO - PROCESS | 4240 | __start_tp_report 09:57:52 INFO - PROCESS | 4240 | _x_x_mozilla_page_load 09:57:52 INFO - PROCESS | 4240 | _x_x_mozilla_page_load_details 09:57:52 INFO - PROCESS | 4240 | |i|pagename|runs| 09:57:52 INFO - PROCESS | 4240 | |0;ai-astar;111;112;120;110;112;111;111;110;111;112 09:57:52 INFO - PROCESS | 4240 | |1;audio-beat-detection;146;116;144;178;116;148;149;117;183;119 09:57:52 INFO - PROCESS | 4240 | |2;audio-dft;160;159;161;159;160;161;161;160;160;161 09:57:52 INFO - PROCESS | 4240 | |3;audio-fft;74;80;75;184;75;76;74;75;162;77 09:57:52 INFO - PROCESS | 4240 | |4;audio-oscillator;83;84;149;83;83;83;134;83;83;140 09:57:52 INFO - PROCESS | 4240 | |5;imaging-gaussian-blur;117;117;117;117;117;116;117;116;116;116 09:57:52 INFO - PROCESS | 4240 | |6;imaging-darkroom;145;145;145;145;145;145;146;147;146;146 09:57:52 INFO - PROCESS | 4240 | |7;imaging-desaturate;94;94;94;94;95;94;94;95;94;94 09:57:52 INFO - PROCESS | 4240 | |8;json-parse-financial;59;53;56;53;54;55;53;53;54;53 09:57:52 INFO - PROCESS | 4240 | |9;json-stringify-tinderbox;58;59;58;59;58;58;58;58;59;58 09:57:52 INFO - PROCESS | 4240 | |10;stanford-crypto-aes;72;72;72;73;76;72;72;73;72;81 09:57:52 INFO - PROCESS | 4240 | |11;stanford-crypto-ccm;127;119;130;114;116;114;116;117;159;127 09:57:52 INFO - PROCESS | 4240 | |12;stanford-crypto-pbkdf2;156;153;150;149;153;153;152;149;148;154 09:57:52 INFO - PROCESS | 4240 | |13;stanford-crypto-sha256-iterative;63;62;60;61;62;62;61;60;61;60 09:57:52 INFO - PROCESS | 4240 | __end_tp_report 09:57:52 INFO - PROCESS | 4240 | __start_cc_report 09:57:52 INFO - PROCESS | 4240 | _x_x_mozilla_cycle_collect,1627 09:57:52 INFO - PROCESS | 4240 | __end_cc_report 09:57:52 INFO - PROCESS | 4240 | __startTimestamp1474304272201__endTimestamp 09:57:52 INFO - PROCESS | 4240 | 09:57:52 INFO - PROCESS | 4240 | ------- Summary: start ------- 09:57:52 INFO - PROCESS | 4240 | Number of tests: 14 09:57:52 INFO - PROCESS | 4240 | 09:57:52 INFO - PROCESS | 4240 | [#0] ai-astar Cycles:10 Average:112.00 Median:111.50 stddev:2.91 (2.6%) stddev-sans-first:3.06 09:57:52 INFO - PROCESS | 4240 | Values: 111.0 112.0 120.0 110.0 112.0 111.0 111.0 110.0 111.0 112.0 09:57:52 INFO - PROCESS | 4240 | 09:57:52 INFO - PROCESS | 4240 | [#1] audio-beat-detection Cycles:10 Average:141.60 Median:147.00 stddev:24.91 (16.9%) stddev-sans-first:26.37 09:57:52 INFO - PROCESS | 4240 | Values: 146.0 116.0 144.0 178.0 116.0 148.0 149.0 117.0 183.0 119.0 09:57:52 INFO - PROCESS | 4240 | 09:57:52 INFO - PROCESS | 4240 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.50 stddev:0.79 (0.5%) stddev-sans-first:0.83 09:57:52 INFO - PROCESS | 4240 | Values: 160.0 159.0 161.0 159.0 160.0 161.0 161.0 160.0 160.0 161.0 09:57:52 INFO - PROCESS | 4240 | 09:57:52 INFO - PROCESS | 4240 | [#3] audio-fft Cycles:10 Average:95.20 Median:75.00 stddev:41.37 (55.2%) stddev-sans-first:43.16 09:57:52 INFO - PROCESS | 4240 | Values: 74.0 80.0 75.0 184.0 75.0 76.0 74.0 75.0 162.0 77.0 09:57:52 INFO - PROCESS | 4240 | 09:57:52 INFO - PROCESS | 4240 | [#4] audio-oscillator Cycles:10 Average:100.50 Median:83.00 stddev:28.18 (33.9%) stddev-sans-first:29.16 09:57:52 INFO - PROCESS | 4240 | Values: 83.0 84.0 149.0 83.0 83.0 83.0 134.0 83.0 83.0 140.0 09:57:52 INFO - PROCESS | 4240 | 09:57:52 INFO - PROCESS | 4240 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:57:52 INFO - PROCESS | 4240 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 116.0 116.0 09:57:52 INFO - PROCESS | 4240 | 09:57:52 INFO - PROCESS | 4240 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:145.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 09:57:52 INFO - PROCESS | 4240 | Values: 145.0 145.0 145.0 145.0 145.0 145.0 146.0 147.0 146.0 146.0 09:57:52 INFO - PROCESS | 4240 | 09:57:52 INFO - PROCESS | 4240 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:57:52 INFO - PROCESS | 4240 | Values: 94.0 94.0 94.0 94.0 95.0 94.0 94.0 95.0 94.0 94.0 09:57:52 INFO - PROCESS | 4240 | 09:57:52 INFO - PROCESS | 4240 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.00 stddev:1.95 (3.6%) stddev-sans-first:1.09 09:57:52 INFO - PROCESS | 4240 | Values: 59.0 53.0 56.0 53.0 54.0 55.0 53.0 53.0 54.0 53.0 09:57:52 INFO - PROCESS | 4240 | 09:57:52 INFO - PROCESS | 4240 | [#9] json-stringify-tinderbox Cycles:10 Average:58.30 Median:58.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 09:57:52 INFO - PROCESS | 4240 | Values: 58.0 59.0 58.0 59.0 58.0 58.0 58.0 58.0 59.0 58.0 09:57:52 INFO - PROCESS | 4240 | 09:57:52 INFO - PROCESS | 4240 | [#10] stanford-crypto-aes Cycles:10 Average:73.50 Median:72.50 stddev:2.92 (4.0%) stddev-sans-first:3.04 09:57:52 INFO - PROCESS | 4240 | Values: 72.0 72.0 72.0 73.0 76.0 72.0 72.0 73.0 72.0 81.0 09:57:52 INFO - PROCESS | 4240 | 09:57:52 INFO - PROCESS | 4240 | [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:123.00 stddev:13.67 (11.1%) stddev-sans-first:14.45 09:57:52 INFO - PROCESS | 4240 | Values: 127.0 119.0 130.0 114.0 116.0 114.0 116.0 117.0 159.0 127.0 09:57:52 INFO - PROCESS | 4240 | 09:57:52 INFO - PROCESS | 4240 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.70 Median:153.00 stddev:2.58 (1.7%) stddev-sans-first:2.22 09:57:52 INFO - PROCESS | 4240 | Values: 156.0 153.0 150.0 149.0 153.0 153.0 152.0 149.0 148.0 154.0 09:57:52 INFO - PROCESS | 4240 | 09:57:52 INFO - PROCESS | 4240 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.50 stddev:1.03 (1.7%) stddev-sans-first:0.87 09:57:52 INFO - PROCESS | 4240 | Values: 63.0 62.0 60.0 61.0 62.0 62.0 61.0 60.0 61.0 60.0 09:57:52 INFO - PROCESS | 4240 | -------- Summary: end -------- 09:57:52 INFO - PROCESS | 4240 | 09:57:53 INFO - TEST-INFO | 4240: exit 0 09:57:53 INFO - TEST-OK | kraken | took 60045ms 09:57:53 INFO - SUITE-END | took 1173s 09:57:53 INFO - Completed test suite (00:19:33) 09:57:53 INFO - javascript benchmark 09:57:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3255.3163835594523, 3272.051105179498, 3290.144562413772, 3314.966850331496, 3336.7831090979084, 5654.802081927133, 5722.856928576788, 5735.311762588495, 5735.369109397963, 5749.741261643231, 5626.521745651625, 5631.549476041917, 5676.3188417389865, 5686.658800471964, 5689.886202275952, 5097.133487307166, 5124.256982737501, 5126.846194614158, 5146.794128234867, 5187.948120518803, 5228.215767634847, 5268.5521730652945, 5273.683578985273, 5284.2866213061325, 5292.867678308035, 5107.208382700687, 5169.767360468769, 5198.818041368553, 5205.817796377128, 5272.551833094191, 5255.237990491396, 5256.789728410839, 5257.894842103175, 5262.315898933114, 5293.470652934714, 5076.035553244908, 5092.3125378073855, 5151.793928242866, 5152.690838549662, 5167.664101833407, 5546.611737178399, 5555.0, 5573.6098473106895, 5576.804811831607, 5609.663420194761, 6055.182550355689, 6068.939310606926, 6070.939290607126, 6072.696365181723, 6076.240469941257, 5929.762809487615, 5994.100884867261, 6005.969970150165, 6072.0, 6115.510759139258, 5713.542916566665, 5716.371199168088, 5720.0, 5725.656460612335, 5739.885202295973, 6211.378224295297, 6242.937570624326, 6247.9062814056915, 6249.125122482856, 6254.937450625435, 5841.620294680833, 5966.134910437962, 6023.0, 6024.186734790747, 6103.328633850273, 6155.507559395238, 6156.476699480594, 6166.599171053867, 6170.0, 6172.814815555541, 6106.053561697944, 6122.5408094392205, 6127.693615319216, 6134.35589263121, 6138.478229350466, 5873.3832947539895, 5974.3428222895445, 6027.517798576104, 6041.5468839837185, 6131.8160455185525, 4642.698224615389, 4659.6505262105475, 4676.906461870743, 4680.555347242007, 4710.528947105262, 4141.378793180987, 4155.58444155582, 4162.4796900387455, 4177.352510360899, 4186.832526698898, 5726.570507211893, 5820.039693450533, 5876.206712093898, 5888.823335299948, 5950.166976623276, 6149.784563273678, 6190.3190649028575, 6191.4118158774845, 6196.783112591038, 6197.721102550396, 5905.261842269716, 5907.0, 5909.970450147722, 5912.645241285537, 5930.614510056833, 5169.844904652867, 5219.765110570033, 5260.263563101169, 5265.894682106336, 5353.7590808413715, 6324.778632747832, 6341.207349081365, 6368.77709280173, 6380.617162970237, 6382.234131904202, 6107.6640784757265, 6141.109539116893, 6156.630602163885, 6165.599236049642, 6171.290301615192, 6195.0, 6214.5960512566535, 6259.530535209969, 6264.561480696308, 6342.207224096988, 5956.791512297049, 6033.276006879202, 6042.45617894374, 6073.331933487401, 6077.848053798513, 6129.570930034854, 6130.601510901777, 6136.846578835565, 6141.017437210025, 6141.539384546266, 5869.911951320819, 5928.970355148197, 5955.136505206634, 5984.102384642339, 5998.550108741774, 5910.202122713551, 5937.9406205937385, 5940.019896716994, 6010.248718910137, 6030.185924900083, 5929.466348028698, 5932.2584676915385, 5932.288125424977, 5936.910946335722, 5938.0, 5843.532517398683, 5953.613015154001, 5969.253843269591, 6000.729967151576, 6027.939720602738, 5748.338941021836, 5771.3651498335985, 5789.1605717171615, 5801.6519008859605, 5870.090136028923], "value": 5627.91662186698, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.5522048023022, 623.0311865049847, 628.7924984755028, 654.6628486329544, 654.6955665615487, 35421.64578354215, 47260.763696181515, 48293.0, 48388.27417588739, 48422.51577484224, 49401.0, 49439.752801236034, 49627.25559116616, 49632.255516167286, 49793.25310120351, 8586.656533738644, 9305.302102342317, 9459.574319155654, 9467.668631597895, 11098.0, 11055.11559075272, 11436.2566433182, 11455.770884582304, 11459.541618335257, 11464.770704585902, 11438.656840294805, 11456.48445819936, 11471.426428678533, 11478.540858365655, 11488.36813975231, 11335.036521895649, 11399.715007124805, 11400.600978965696, 11408.315501069921, 11409.942950285278, 11227.60703375386, 11261.493232804543, 11278.548858045668, 11280.0, 11283.37941413222, 46152.53847461516, 46240.76879615614, 46458.53541464576, 46494.53505464936, 46645.533544664795, 3748.775073495599, 3915.0, 4125.50493940729, 4183.874483765492, 4588.564086411786, 35236.47145292823, 35424.822875885715, 35556.46665300023, 37328.066798330256, 37369.25261494782, 4709.952900470986, 4726.456457507396, 4802.375691160127, 4807.519248075199, 4853.538913803183, 418751.0, 421568.0, 423647.0, 424596.0, 425509.0, 68626.313736863, 69203.30796692069, 69254.30745692579, 69289.0, 69320.0, 40597.79701101476, 41335.586644133175, 41471.585284147375, 43674.34488482675, 43776.343354849705, 19854.50363740918, 20002.19991200365, 20021.198092171482, 20026.499337516678, 20032.69950950737, 13160.14459060158, 14093.0, 14699.0, 15039.624009399853, 15124.924375378272, 21155.365339039854, 21213.0, 21393.93030348476, 21446.78553214479, 21651.13395464195, 7462.925370746331, 7632.122305934889, 7635.465517413728, 7658.234176582298, 7703.614819259014, 1025.4923812712755, 1075.93544387337, 1104.7735214281054, 1148.064327573042, 1148.5757660501085, 34486.82756586201, 35833.1041723959, 35999.0, 36036.09909752277, 36223.63776362203, 1135.6422726841183, 1158.4207896051973, 1183.0476466444422, 1203.3141109567637, 1235.3390935849457, 18601.348952786586, 18664.0, 18672.0, 18745.156467958703, 18908.527286817938, 626814.0, 634887.0, 635663.0, 640416.0, 649004.0, 65963.34036659572, 66316.0, 66353.33646663568, 66381.0, 66531.33468665251, 1315.7565850317721, 1422.3686180566076, 1464.1288433382333, 1467.8935727231244, 1476.468471350313, 33794.66205337915, 35916.28167436689, 36463.27073458569, 36634.8168259157, 36656.26687466289, 55597.722011389684, 56138.15792763193, 56283.437165627816, 56284.71857640849, 56372.0, 441223.0, 442817.0, 442905.0, 444368.0, 444907.0, 28140.85929570339, 30375.240618984702, 30993.225169370948, 31170.68829311678, 31875.20311992219, 52559.0, 52582.73708631432, 52642.73678631582, 53390.19914701205, 54019.18971215514, 43917.560824391345, 44560.77719611381, 46319.0, 46870.76564617155, 47217.527824721314, 44328.0, 46047.539524604326, 46140.0, 46705.2994205094, 46746.53253467422, 45060.0, 45617.0, 46147.53852461433, 46148.76925615351, 46207.0, 36863.26273474462, 39839.80080099581, 40782.0, 40812.91870812895, 41192.79403602963, 29942.25144371408, 29961.889752853935, 30352.84823575868, 30395.392092157592, 30781.69218307878], "value": 22369.58998341257, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.7145505328848, 420.8379773787093, 425.76157351882955, 434.2660903073805, 434.6045098959946, 19357.612847743036, 20466.181352745873, 20991.68512472312, 21377.893110534464, 21581.24465643703, 20178.293759718414, 20274.0, 20712.171513139496, 20836.791632083674, 20987.265445709367, 5812.651240925547, 6042.365551617061, 6098.298695649991, 6112.69436528174, 6119.296280927706, 5793.217915581385, 5919.822405327847, 5942.197803296564, 5946.43508866657, 6002.819915402545, 5891.793787217448, 5895.941040589582, 5897.675627840467, 5907.645541267538, 5913.615614985012, 5754.223179870727, 5955.106733989893, 5970.641761494303, 5988.281406231258, 5990.191324171225, 5281.9207711884355, 5428.511433970962, 5429.267048948381, 5432.022235997519, 5437.075697131497, 13880.51418200358, 14145.363458644386, 14345.569632911029, 14387.0, 14581.416743330254, 2410.68661074061, 2423.285130886393, 2546.5670835957744, 2840.900568480093, 2862.041216192578, 11132.220744547885, 11183.0, 11230.101591872712, 15108.093514389171, 15361.539153825403, 1982.3557343863158, 2005.929792457257, 2075.2529089527757, 2120.8833514156713, 2122.352682431858, 89863.99999999935, 90041.0, 91316.54341728316, 93256.0, 95317.0, 11849.466773995191, 11964.940175299242, 11969.521219151136, 12052.276863388224, 12299.815502767466, 17109.315627375014, 17177.82822171787, 17214.483565493054, 17221.655566888592, 17233.0, 8563.871541926876, 8868.0, 8946.105389461132, 8998.640054397882, 9236.122568355995, 8951.149640784115, 8980.101989800967, 8991.910080899239, 9125.589348479334, 9245.676401325922, 4781.354517140211, 4803.519648035169, 4819.228923372244, 4822.590079843252, 4926.778294976734, 2109.7995690409693, 2133.775953524889, 2152.5264441822774, 2157.8381621378458, 2235.7764223577838, 961.7018724195382, 971.3491960386558, 1032.9896701033044, 1046.8796088449926, 1079.3577821196375, 20924.58150836975, 21332.253371132247, 21396.465088372603, 21577.352679419644, 21647.67528487074, 1009.6213919780082, 1045.8588090607823, 1046.874375074996, 1052.752603138262, 1055.3667799320347, 11365.545378184945, 11766.941165294118, 11916.582919597773, 11977.700557486132, 12084.637460876187, 558759.0, 559342.0, 565817.0, 566820.0, 569275.0, 64986.67506662531, 67857.3214267851, 96357.0, 98433.50783246181, 100933.49533252287, 995.4077323992213, 1000.3697670467559, 1017.7353887989176, 1030.927835051553, 1032.912202462784, 18143.092845357416, 18200.45398638043, 18721.25114995385, 18963.33628323002, 18978.24087036503, 45125.54874451213, 45474.31788523109, 45476.54523454723, 45757.54242457533, 46215.30677039914, 61559.0, 61800.381996179465, 62513.37486625076, 63201.683991579746, 64888.0, 7890.092639346549, 7890.250426209501, 8175.550344731097, 8294.377921656021, 8323.209295116954, 13055.34723263814, 13152.736945261233, 13248.602541923758, 13659.453621855015, 14006.229657368942, 11070.723231919266, 11869.406529673655, 11950.462229199533, 11975.161738678209, 12013.879861201625, 11276.774464510829, 11282.0, 11329.206955513035, 11571.479283432434, 11915.16593838423, 6595.439387651912, 9745.463999480096, 11998.700032499257, 12442.564510242099, 12622.55821046259, 8802.383833131875, 8922.330825188006, 8940.374173807772, 8994.190522852974, 9005.549722513979, 11513.942430287794, 12019.4591243396, 12090.879091209326, 12160.574379896661, 12851.293178874877], "value": 9604.165667531199, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1439.726451974124, 1564.4446221591336, 1572.866306363959, 1720.320473413002, 1851.4168037068323, 9174.90825091749, 9528.85706714399, 9561.474118923456, 9843.409395436262, 9849.803003939916, 9655.565499552536, 9738.0, 9740.172085372751, 9750.756231094227, 9759.804803903917, 2527.3428908483834, 2573.4853029394103, 2574.3306740247576, 2576.8582727949956, 2578.5358635445614, 2470.752924707524, 2532.3289328327915, 2537.1246919812634, 2538.200466852945, 2539.2889990802605, 2482.6648402465617, 2487.2911220302217, 2598.4803039392104, 2601.6617839680816, 2602.1152808045263, 2453.705555333371, 2474.7772700456953, 2476.0, 2480.6403071554705, 2487.117073438921, 2439.9512009759883, 2441.8901149448316, 2457.496213276274, 2459.7417271186628, 2487.601983682602, 9722.319437639368, 9744.951275243578, 9797.608095676165, 9818.067283608047, 9829.164521015722, 5666.631668941505, 5857.238558987347, 5857.824265272006, 5859.355470898197, 5864.237649105589, 3621.5110960020324, 3621.692156166729, 3700.2414505026404, 3707.2400157967554, 3722.9441558376648, 1216.9756604867941, 1234.5061975209899, 1238.777020136375, 1251.393074358935, 1265.1839563481594, 13657.317134143253, 13734.725305493934, 13745.450181992708, 13745.793813092812, 13792.103513271706, 4794.856154315376, 4855.635827312895, 4938.111139994836, 4982.053409852189, 5020.7740651670765, 10763.0, 10783.56865725378, 10791.082757965503, 10791.406472643923, 10800.351978881292, 5735.254416925773, 5742.28221472316, 5744.195812586241, 5752.913706294409, 5755.597108202385, 4735.195016847144, 4877.146499362669, 5327.893442131136, 5403.729813509309, 5423.322084739408, 4342.305231163062, 4352.73883566987, 4399.12017596482, 4423.115376924564, 4436.112777444524, 1278.8081787731915, 1295.4105881823748, 1297.6107167849607, 1302.4855182203166, 1305.5039085147582, 856.7472595584318, 986.40815510693, 1012.448215722433, 1056.434807378049, 1077.4343469678324, 5999.5500337473995, 6172.506199504029, 6255.43701066897, 6261.405166509175, 6518.380753828379, 1018.9592416303265, 1050.0706874416196, 1069.8127827630133, 1072.463768115942, 1083.0793825248477, 4195.8741237762915, 4356.869293921188, 4482.932756008662, 4584.014436896084, 4597.747123908217, 12068.517259309705, 12518.123731338901, 12530.185537940004, 12542.933850622616, 12580.182288151242, 12137.69655758613, 12257.0, 12377.938110309571, 12395.876041239653, 12435.129540932048, 9065.954670226607, 9078.636854525877, 9182.219511341476, 9241.491717955443, 9297.0, 6399.616023038632, 6456.515761317826, 6541.70562324706, 6573.244076931214, 6644.867102657823, 10549.630762923261, 10688.57245710194, 10777.568897244022, 11064.446777661246, 11218.439078045902, 10198.694039178836, 10885.619003334845, 11083.72290692739, 11090.667279981613, 11132.608423947007, 8547.0, 8774.38579299443, 8856.468611883307, 8864.42381245217, 8873.378863479495, 9476.194523465583, 9621.037896210602, 9628.71113866585, 9631.229501639993, 9632.27757918145, 8396.454230475243, 8430.494170349799, 8433.28317093054, 8632.870506942276, 8965.955170224108, 8129.430939834155, 8301.377396695392, 8305.418620696493, 8434.156584341517, 8447.577621118797, 7510.812229694301, 7667.808304792425, 7699.0, 7775.455718099905, 7777.0, 5434.891302174014, 5470.890582188414, 5550.69471179089, 5615.466530679579, 5624.915626265692, 5662.207290979184, 5666.263385759825, 5680.545556355399, 5813.215215945962, 5819.185314056121], "value": 5062.729772458215, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.7052769881751, 345.3162737779197, 348.08627353197863, 351.0714161044037, 353.63045617329885, 5603.215549823028, 5633.098704207323, 5644.125160600113, 5646.18130371096, 5670.319561652597, 5500.80747173849, 5513.448655134484, 5586.469285417878, 5590.91613625796, 5607.158926161078, 3911.8239679214503, 4168.4581004469455, 4276.358546218061, 4321.654267658596, 4326.199653064177, 4281.529031806499, 4291.35629655551, 4297.785110744466, 4302.741835489865, 4323.891902702442, 4306.18182545317, 4308.728924967142, 4318.935215971763, 4321.891952701176, 4322.222000039991, 4087.4686290782306, 4257.787110644455, 4290.69965102443, 4324.97837510812, 4356.3683265926265, 3768.7186356638877, 3772.264408440355, 3786.753860999054, 3796.468494410785, 3806.600306967784, 5359.464053594648, 5361.731913404353, 5367.43641917597, 5375.865603359908, 5386.407495175527, 4827.975860120712, 4842.564169224752, 4851.919031415042, 4903.828366007173, 4909.754512274372, 5648.6893220872835, 5687.601867869251, 5712.285964254468, 5715.371309155989, 5725.227094342252, 4138.482689663792, 4162.396452514398, 4165.770882601456, 4172.728772629769, 4174.6621891740415, 5753.568482363798, 5925.31858836235, 5956.195913551702, 5978.701064946778, 6043.335233124353, 4120.278951183531, 4167.416561681306, 4168.499780026416, 4173.707840451139, 4174.499060112746, 4223.873283801491, 4225.535191128974, 4232.0, 4241.681873859411, 4275.8075886585175, 4700.55344742249, 4875.34182885313, 4895.583875370633, 4905.7301848397965, 4910.508949105132, 5728.427157284238, 5737.3361725100085, 5764.13537969308, 5770.3652598215, 5846.386129456431, 1471.8528147185195, 1493.7759336099673, 1497.6595946627708, 1497.827749808764, 1501.5345242974495, 928.7601052594727, 953.4708236928433, 955.7276176289863, 963.2486660404895, 977.3256453047517, 4987.351644286292, 5026.648134630541, 5030.673006254582, 5032.672876263105, 5055.519725626134, 5196.181336532183, 5203.843884683465, 5233.215017747369, 5304.443033481506, 5310.7610157543, 5030.974845125751, 5061.569766569809, 5070.847874563769, 5306.734663266821, 5728.312602487728, 3166.1293144385477, 3186.3627274545183, 3190.8512935343256, 3193.1538142392283, 3198.2164369729567, 6045.274567051982, 6232.470240029557, 6246.500279977591, 6266.874662506724, 6273.341299163613, 5816.447437493432, 6070.0, 6084.361142080106, 6110.725017374229, 6131.417515336036, 6056.909146362808, 6101.871153836554, 6126.713390188022, 6138.846528836815, 6176.166217560662, 5934.457041169241, 5958.344582096052, 5966.880662386815, 5972.432618901197, 5988.8802223956145, 5784.739686714024, 5826.94173058264, 5847.473727364388, 5871.2073870027, 5873.500752436091, 6165.0, 6167.84580385494, 6177.733564619152, 6193.690315484298, 6202.054024338403, 4069.2675318442966, 4286.271333873186, 4292.7424354538825, 4295.806688699078, 4312.654987601047, 5880.558958078076, 5908.438698323653, 5908.615939963888, 5922.763089476372, 5926.555508336978, 5795.478406943395, 5800.970995144998, 5803.216565763737, 5815.41845815415, 5831.825045248649, 5638.464345887134, 5736.42635736423, 5739.311282646109, 5769.798057067983, 5820.4390068369785, 5732.254806875079, 5738.741756620879, 5746.798862039808, 5760.711964401847, 5778.971105144447, 5589.580781441489, 5769.567282453917, 5816.214811000461, 5824.534037276923, 5831.241938547962, 5625.296837895263, 5641.605087643826, 5656.123300888369, 5661.490465858093, 5674.602777805679], "value": 4378.561155044513, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [777.8133248020475, 794.7139029949219, 826.2976470000505, 826.3843436639704, 927.9536023198839, 15693.52919412418, 16080.11559364236, 16468.67062658746, 16582.08798516081, 16680.74978875318, 14407.495737649184, 14515.201663908481, 14516.927415362934, 14537.345819438124, 14537.418503259856, 753.5403403923602, 861.4013260783757, 890.3500444675375, 904.5115637555712, 910.5856835139996, 898.9865152022725, 911.0525053943891, 912.2519533982137, 912.4616476278994, 912.7626817027588, 954.3987288008545, 955.4028732042474, 957.3872721458272, 957.6983250276176, 957.760559860035, 950.0262231212993, 952.8618350339162, 953.3421938862198, 953.3803028031765, 953.8092381523688, 699.6117154978984, 699.919509256437, 699.9615021173834, 700.5376451542008, 700.6321681117403, 14757.409703611842, 14766.852331476763, 14776.670099691079, 14783.556493305217, 14802.111873287637, 16268.26792794327, 16703.074777427293, 16756.245968931427, 16813.663726725397, 17397.826021739747, 13438.798418023738, 13469.326533673277, 13506.392212350467, 13684.384202710902, 13763.724725505434, 4086.8978275543054, 4182.665386769051, 4184.0, 4200.894977625553, 4264.765437900915, 85423.71864422038, 90642.5467872663, 90819.54590227072, 92593.0, 92650.0, 12539.37303134858, 12638.810417843739, 12685.873141268468, 12825.807612885814, 12846.87970187033, 23772.405689857893, 23827.642585361235, 23936.640950385758, 23973.28080157598, 24042.39894002629, 8242.505449673039, 8370.623321950527, 8376.181540345346, 8399.370047246359, 8444.282236010007, 4790.8323208688225, 4972.627052971042, 5051.494850514919, 5065.366829146356, 5148.0, 3845.73079884411, 3988.082740969593, 3991.2815693174925, 3992.6207010333974, 3992.941870404345, 628.739073284591, 635.3614617309574, 641.4739913271073, 641.7048157847349, 642.1491523730982, 709.0888208651961, 711.4965740001087, 728.9890651640229, 749.7713197474823, 751.4777229825219, 9515.714528564155, 9728.95135524332, 9733.318667693193, 9818.607255709834, 9889.20886329092, 621.1862460177182, 658.4403257231411, 701.225146213437, 731.9414446844239, 735.6285076036568, 4664.836730714476, 4854.951450485521, 4865.099956508057, 4871.732054736953, 4941.209406494943, 120710.0, 121021.0, 122447.38776306061, 122893.0, 125059.3747031259, 105615.41576876353, 106660.0, 107119.0, 107760.46119769508, 110391.34412983812, 90344.54827725951, 91340.99999999868, 94607.0, 96403.0, 97225.0, 8896.644134234559, 9263.212626926786, 9370.718878433658, 9688.5640146195, 9778.853317200106, 50412.0, 51794.78462584514, 52322.73838630782, 52691.0, 55264.44735552593, 74645.0, 76622.0, 76843.0, 77083.0, 78919.0, 12908.483660653468, 13445.798313025116, 13523.932380338036, 13645.727085458433, 13895.235762033186, 13962.301884905512, 14189.361478733275, 14211.147331160164, 14770.556883293519, 15408.537743867702, 12484.0, 12582.127963424955, 12669.93665031669, 13155.779245744521, 13353.265570393722, 13122.540711075448, 13326.267055312052, 13421.932890335485, 13514.391852366864, 13583.728325433634, 12125.69685757863, 12380.257184568954, 12898.48406063747, 13091.934540327238, 13355.399007044512, 10542.314749541256, 10666.679999600025, 10955.945220273848, 10957.506912188812, 11202.38386888729, 10638.468076596295, 10731.248812583357, 10772.353658780497, 10868.61959831402, 11031.83452248201], "value": 7922.625561916775, "unit": "score"}], "name": "dromaeo_css", "value": 7724.122277447522, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 120.0, 110.0, 112.0, 111.0, 111.0, 110.0, 111.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 116.0, 144.0, 178.0, 116.0, 148.0, 149.0, 117.0, 183.0, 119.0], "value": 141.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 161.0, 159.0, 160.0, 161.0, 161.0, 160.0, 160.0, 161.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 80.0, 75.0, 184.0, 75.0, 76.0, 74.0, 75.0, 162.0, 77.0], "value": 95.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 149.0, 83.0, 83.0, 83.0, 134.0, 83.0, 83.0, 140.0], "value": 100.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0, 147.0, 146.0, 146.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 53.0, 56.0, 53.0, 54.0, 55.0, 53.0, 53.0, 54.0, 53.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 58.0, 59.0, 58.0, 58.0, 58.0, 58.0, 59.0, 58.0], "value": 58.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 72.0, 73.0, 76.0, 72.0, 72.0, 73.0, 72.0, 81.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 119.0, 130.0, 114.0, 116.0, 114.0, 116.0, 117.0, 159.0, 127.0], "value": 123.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 153.0, 150.0, 149.0, 153.0, 153.0, 152.0, 149.0, 148.0, 154.0], "value": 151.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 60.0, 61.0, 62.0, 62.0, 61.0, 60.0, 61.0, 60.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1488.7000000000003, "alertThreshold": 2.0}]} 09:57:53 INFO - Return code: 0 09:57:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:57:53 INFO - # TBPL SUCCESS # 09:57:53 INFO - Running post-action listener: _resource_record_post_action 09:57:53 INFO - [mozharness: 2016-09-19 16:57:53.532606Z] Finished run-tests step (success) 09:57:53 INFO - Running post-run listener: _resource_record_post_run 09:57:53 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 288702464; Read time: 0; Write time: 257360 09:57:53 INFO - TinderboxPrint: CPU usage
12.9% 09:57:53 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:57:53 INFO - TinderboxPrint: I/O write bytes / time
288,702,464 / 257,360 09:57:53 INFO - TinderboxPrint: CPU idle
8,259.3 (87.1%) 09:57:53 INFO - TinderboxPrint: CPU user
1,180.0 (12.4%) 09:57:53 INFO - TinderboxPrint: Swap in / out
0 / 0 09:57:53 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 70086656; Read time: 0; Write time: 89412 09:57:53 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 218615808; Read time: 0; Write time: 167948 09:57:54 INFO - Running post-run listener: _upload_blobber_files 09:57:54 INFO - Blob upload gear active. 09:57:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:57:54 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 09:57:54 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'] 09:57:54 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 09:57:54 INFO - (blobuploader) - INFO - Open directory for files ... 09:57:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:57:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:57:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:55 INFO - (blobuploader) - INFO - Done attempting. 09:57:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:57:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:58:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:00 INFO - (blobuploader) - INFO - Done attempting. 09:58:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:58:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:58:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:01 INFO - (blobuploader) - INFO - Done attempting. 09:58:01 INFO - (blobuploader) - INFO - Iteration through files over. 09:58:01 INFO - Return code: 0 09:58:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:58:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:58:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30ff120433cf528c4e74dd1e9b0daa35d20f7b286c440f8d1262a4807af5aa3feba8e7d93fb55600360f1dfcfa65d5cacc110225e2de1fc97e6ca8bae960a5d3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c3675edccd133740fb4792c17e1d63eed3d37f60fb01b2794772459d49a908d52184f912a89c13d910df8ba72c07de950aed708a6a00c7a718738156964f3db6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/186792fc27ca06d04a7fc99d1af3b98807cf5613ae52100c69467223ea0f3fb64d93ca722a798c4ad90a7ff7978ec85de1f9cde8139263727010b3cebb107282"} 09:58:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:58:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:58:01 INFO - Contents: 09:58:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30ff120433cf528c4e74dd1e9b0daa35d20f7b286c440f8d1262a4807af5aa3feba8e7d93fb55600360f1dfcfa65d5cacc110225e2de1fc97e6ca8bae960a5d3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c3675edccd133740fb4792c17e1d63eed3d37f60fb01b2794772459d49a908d52184f912a89c13d910df8ba72c07de950aed708a6a00c7a718738156964f3db6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/186792fc27ca06d04a7fc99d1af3b98807cf5613ae52100c69467223ea0f3fb64d93ca722a798c4ad90a7ff7978ec85de1f9cde8139263727010b3cebb107282"} 09:58:01 INFO - Running post-run listener: copy_logs_to_upload_dir 09:58:01 INFO - Copying logs to upload dir... 09:58:01 INFO - mkdir: /builds/slave/test/build/upload/logs 09:58:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.789088 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-19 09:58:00.494146) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 09:58:00.498207) ========= 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=836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448 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/30ff120433cf528c4e74dd1e9b0daa35d20f7b286c440f8d1262a4807af5aa3feba8e7d93fb55600360f1dfcfa65d5cacc110225e2de1fc97e6ca8bae960a5d3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c3675edccd133740fb4792c17e1d63eed3d37f60fb01b2794772459d49a908d52184f912a89c13d910df8ba72c07de950aed708a6a00c7a718738156964f3db6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/186792fc27ca06d04a7fc99d1af3b98807cf5613ae52100c69467223ea0f3fb64d93ca722a798c4ad90a7ff7978ec85de1f9cde8139263727010b3cebb107282"} build_url:https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011203 build_url: 'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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/30ff120433cf528c4e74dd1e9b0daa35d20f7b286c440f8d1262a4807af5aa3feba8e7d93fb55600360f1dfcfa65d5cacc110225e2de1fc97e6ca8bae960a5d3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c3675edccd133740fb4792c17e1d63eed3d37f60fb01b2794772459d49a908d52184f912a89c13d910df8ba72c07de950aed708a6a00c7a718738156964f3db6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/186792fc27ca06d04a7fc99d1af3b98807cf5613ae52100c69467223ea0f3fb64d93ca722a798c4ad90a7ff7978ec85de1f9cde8139263727010b3cebb107282"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 09:58:00.523887) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 09:58:00.524224) ========= 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=836cfdc75598d54cb747368e000001a9-1474302601.858350-1086583448 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 09:58:00.606770) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 09:58:00.607104) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 09:58:00.607411) ========= ========= Total master_lag: 0.16 =========