builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-068 starttime: 1473338418.85 results: success (0) buildid: 20160908025615 builduid: a419c96531e3431bbaf02484b3673641 revision: 46c6e8bb7f6f6d7281baa761cc931ccd46feca5d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 05:40:18.852860) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 05:40:18.853206) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 05:40:18.853470) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 05:40:18.867821) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 05:40:18.868089) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-08 05:40:20-- 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% 89.3M=0s 2016-09-08 05:40:20 (89.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124966 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 05:40:19.006366) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 05:40:19.006650) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018253 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 05:40:19.055579) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 05:40:19.055962) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 46c6e8bb7f6f6d7281baa761cc931ccd46feca5d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 46c6e8bb7f6f6d7281baa761cc931ccd46feca5d --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=97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-08 05:40:20,447 truncating revision to first 12 chars 2016-09-08 05:40:20,447 Setting DEBUG logging. 2016-09-08 05:40:20,447 attempt 1/10 2016-09-08 05:40:20,447 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/46c6e8bb7f6f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 05:40:21,093 unpacking tar archive at: mozilla-aurora-46c6e8bb7f6f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.965634 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 05:40:20.035453) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 05:40:20.035776) ========= script_repo_revision: 46c6e8bb7f6f6d7281baa761cc931ccd46feca5d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 05:40:20.036151) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 05:40:20.036431) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 05:40:20.048208) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-09-08 05:40:20.048514) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:40:21 INFO - MultiFileLogger online at 20160908 05:40:21 in /builds/slave/test 05:40:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:40:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:40:21 INFO - {'append_to_log': False, 05:40:21 INFO - 'base_work_dir': '/builds/slave/test', 05:40:21 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:40:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:40:21 INFO - 'branch': 'Mozilla-Aurora', 05:40:21 INFO - 'buildbot_json_path': 'buildprops.json', 05:40:21 INFO - 'config_files': ('talos/linux_config.py',), 05:40:21 INFO - 'default_actions': ('clobber', 05:40:21 INFO - 'read-buildbot-config', 05:40:21 INFO - 'download-and-extract', 05:40:21 INFO - 'populate-webroot', 05:40:21 INFO - 'create-virtualenv', 05:40:21 INFO - 'install', 05:40:21 INFO - 'run-tests'), 05:40:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:40:21 INFO - 'download_minidump_stackwalk': True, 05:40:21 INFO - 'download_symbols': 'ondemand', 05:40:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:40:21 INFO - 'tooltool.py': '/tools/tooltool.py', 05:40:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:40:21 INFO - '/tools/misc-python/virtualenv.py')}, 05:40:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:40:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:40:21 INFO - 'installer_path': 'installer.exe', 05:40:21 INFO - 'log_level': 'info', 05:40:21 INFO - 'log_name': 'talos', 05:40:21 INFO - 'log_to_console': True, 05:40:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:40:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:40:21 INFO - 'opt_config_files': (), 05:40:21 INFO - 'pip_index': False, 05:40:21 INFO - 'sps_profile': False, 05:40:21 INFO - 'sps_profile_interval': 0, 05:40:21 INFO - 'suite': 'dromaeojs', 05:40:21 INFO - 'system_bits': '32', 05:40:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:40:21 INFO - 'title': 'talos-linux64-ix-068', 05:40:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:40:21 INFO - 'use_talos_json': True, 05:40:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:40:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:40:21 INFO - 'work_dir': 'build'} 05:40:21 INFO - [mozharness: 2016-09-08 12:40:21.539155Z] Running clobber step. 05:40:21 INFO - Running pre-action listener: _resource_record_pre_action 05:40:21 INFO - Running main action method: clobber 05:40:21 INFO - rmtree: /builds/slave/test/build 05:40:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:40:22 INFO - Running post-action listener: _resource_record_post_action 05:40:22 INFO - [mozharness: 2016-09-08 12:40:22.003467Z] Finished clobber step (success) 05:40:22 INFO - [mozharness: 2016-09-08 12:40:22.003569Z] Running read-buildbot-config step. 05:40:22 INFO - Running pre-action listener: _resource_record_pre_action 05:40:22 INFO - Running main action method: read_buildbot_config 05:40:22 INFO - Using buildbot properties: 05:40:22 INFO - { 05:40:22 INFO - "project": "", 05:40:22 INFO - "product": "firefox", 05:40:22 INFO - "script_repo_revision": "production", 05:40:22 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 05:40:22 INFO - "repository": "", 05:40:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 05:40:22 INFO - "buildid": "20160908025615", 05:40:22 INFO - "pgo_build": "True", 05:40:22 INFO - "basedir": "/builds/slave/test", 05:40:22 INFO - "buildnumber": 740, 05:40:22 INFO - "slavename": "talos-linux64-ix-068", 05:40:22 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:40:22 INFO - "platform": "ubuntu64_hw", 05:40:22 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 05:40:22 INFO - "branch": "mozilla-aurora", 05:40:22 INFO - "repo_path": "releases/mozilla-aurora", 05:40:22 INFO - "revision": "46c6e8bb7f6f6d7281baa761cc931ccd46feca5d", 05:40:22 INFO - "stage_platform": "linux64-pgo", 05:40:22 INFO - "builduid": "a419c96531e3431bbaf02484b3673641", 05:40:22 INFO - "slavebuilddir": "test" 05:40:22 INFO - } 05:40:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 05:40:22 INFO - Running post-action listener: _resource_record_post_action 05:40:22 INFO - [mozharness: 2016-09-08 12:40:22.006014Z] Finished read-buildbot-config step (success) 05:40:22 INFO - [mozharness: 2016-09-08 12:40:22.006104Z] Running download-and-extract step. 05:40:22 INFO - Running pre-action listener: _resource_record_pre_action 05:40:22 INFO - Running main action method: download_and_extract 05:40:22 INFO - mkdir: /builds/slave/test/build/tests 05:40:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:22 INFO - https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:40:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:40:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:40:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:40:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:40:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:40:22 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:40:22 INFO - retry: Failed, sleeping 30 seconds before retrying 05:40:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 05:40:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:40:52 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:40:52 INFO - retry: Failed, sleeping 60 seconds before retrying 05:41:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 05:41:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:41:52 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:41:52 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 05:41:52 INFO - Caught exception: HTTP Error 404: Not Found 05:41:52 INFO - Caught exception: HTTP Error 404: Not Found 05:41:52 INFO - Caught exception: HTTP Error 404: Not Found 05:41:52 INFO - trying https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:41:52 INFO - Downloading https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:41:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:41:53 INFO - Downloaded 1361 bytes. 05:41:53 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:41:53 INFO - Using the following test package requirements: 05:41:53 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 05:41:53 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:41:53 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 05:41:53 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:41:53 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 05:41:53 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:41:53 INFO - u'jsshell-linux-x86_64.zip'], 05:41:53 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:41:53 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 05:41:53 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 05:41:53 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:41:53 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 05:41:53 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:41:53 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 05:41:53 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:41:53 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 05:41:53 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:41:53 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 05:41:53 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:41: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')]} 05:41:53 INFO - https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:41:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:41:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:41:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:41:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:41:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:41:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:41:53 INFO - retry: Failed, sleeping 30 seconds before retrying 05:42:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 05:42:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:42:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:42:23 INFO - retry: Failed, sleeping 60 seconds before retrying 05:43:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 05:43:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:43:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:43:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 05:43:23 INFO - Caught exception: HTTP Error 404: Not Found 05:43:23 INFO - Caught exception: HTTP Error 404: Not Found 05:43:23 INFO - Caught exception: HTTP Error 404: Not Found 05:43:23 INFO - trying https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:43:23 INFO - Downloading https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:43:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:43:24 INFO - Downloaded 21769303 bytes. 05:43:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:43:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:43:25 INFO - Return code: 0 05:43:25 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:43:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:25 INFO - https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:43:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:43:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:43:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:43:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:43:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:43:25 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:43:25 INFO - retry: Failed, sleeping 30 seconds before retrying 05:43:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 05:43:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:43:55 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:43:55 INFO - retry: Failed, sleeping 60 seconds before retrying 05:44:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 05:44:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:44:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:44:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 05:44:55 INFO - Caught exception: HTTP Error 404: Not Found 05:44:55 INFO - Caught exception: HTTP Error 404: Not Found 05:44:55 INFO - Caught exception: HTTP Error 404: Not Found 05:44:55 INFO - trying https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:44:55 INFO - Downloading https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:44:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:44:56 INFO - Downloaded 21769303 bytes. 05:44:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:44:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:44:57 INFO - Return code: 0 05:44: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')]} 05:44:57 INFO - https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:44:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:44:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:44:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:44:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:44:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:44:57 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:44:57 INFO - retry: Failed, sleeping 30 seconds before retrying 05:45:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 05:45:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:45:27 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:45:27 INFO - retry: Failed, sleeping 60 seconds before retrying 05:46:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 05:46:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:46:27 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:46:27 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 05:46:27 INFO - Caught exception: HTTP Error 404: Not Found 05:46:27 INFO - Caught exception: HTTP Error 404: Not Found 05:46:27 INFO - Caught exception: HTTP Error 404: Not Found 05:46:27 INFO - trying https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:46:27 INFO - Downloading https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:46:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:46:28 INFO - Downloaded 14413740 bytes. 05:46:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:46:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:46:28 INFO - Return code: 0 05:46:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:28 INFO - https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:46:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:46:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:46:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:46:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:46:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:46:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:46:28 INFO - retry: Failed, sleeping 30 seconds before retrying 05:46:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 05:46:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:46:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:46:58 INFO - retry: Failed, sleeping 60 seconds before retrying 05:47:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 05:47:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:47:59 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:47:59 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 05:47:59 INFO - Caught exception: HTTP Error 404: Not Found 05:47:59 INFO - Caught exception: HTTP Error 404: Not Found 05:47:59 INFO - Caught exception: HTTP Error 404: Not Found 05:47:59 INFO - trying https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:47:59 INFO - Downloading https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:47:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:48:00 INFO - Downloaded 63903198 bytes. 05:48:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:48:00 INFO - mkdir: /builds/slave/test/properties 05:48:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:48:00 INFO - Writing to file /builds/slave/test/properties/build_url 05:48:00 INFO - Contents: 05:48:00 INFO - build_url:https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:48:01 INFO - Running post-action listener: _resource_record_post_action 05:48:01 INFO - Running post-action listener: set_extra_try_arguments 05:48:01 INFO - [mozharness: 2016-09-08 12:48:01.014756Z] Finished download-and-extract step (success) 05:48:01 INFO - [mozharness: 2016-09-08 12:48:01.014990Z] Running populate-webroot step. 05:48:01 INFO - Running pre-action listener: _resource_record_pre_action 05:48:01 INFO - Running main action method: populate_webroot 05:48:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:48:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:48:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:48:01 INFO - '--tppagecycles', 05:48:01 INFO - '7'], 05:48:01 INFO - 'tests': ['tsvgm']}}, 05:48:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:48:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:48:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:48:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:48:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:48:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:48:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:01 INFO - 'talos_options': ['--disable-e10s'], 05:48:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:48:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:48:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:01 INFO - 'talos_options': ['--disable-e10s'], 05:48:01 INFO - 'tests': ['damp', 'tps']}, 05:48:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:01 INFO - 'tests': ['damp', 'tps']}, 05:48:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:48:01 INFO - 'tests': ['dromaeo_dom']}, 05:48:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:48:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:48:01 INFO - 'tests': ['basic_compositor_video']}, 05:48:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:48:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:48:01 INFO - 'tests': ['a11yr', 05:48:01 INFO - 'ts_paint', 05:48:01 INFO - 'tpaint', 05:48:01 INFO - 'sessionrestore', 05:48:01 INFO - 'sessionrestore_no_auto_restore', 05:48:01 INFO - 'tabpaint']}, 05:48:01 INFO - 'other-e10s': {'tests': ['a11yr', 05:48:01 INFO - 'ts_paint', 05:48:01 INFO - 'tpaint', 05:48:01 INFO - 'sessionrestore', 05:48:01 INFO - 'sessionrestore_no_auto_restore', 05:48:01 INFO - 'tabpaint']}, 05:48:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:48:01 INFO - 'ts_paint', 05:48:01 INFO - 'tpaint', 05:48:01 INFO - 'sessionrestore', 05:48:01 INFO - 'sessionrestore_no_auto_restore', 05:48:01 INFO - 'tabpaint']}, 05:48:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:48:01 INFO - 'ts_paint', 05:48:01 INFO - 'tpaint', 05:48:01 INFO - 'sessionrestore', 05:48:01 INFO - 'sessionrestore_no_auto_restore', 05:48:01 INFO - 'tabpaint']}, 05:48:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:48:01 INFO - 'tests': ['a11yr', 05:48:01 INFO - 'ts_paint', 05:48:01 INFO - 'tpaint', 05:48:01 INFO - 'sessionrestore', 05:48:01 INFO - 'sessionrestore_no_auto_restore', 05:48:01 INFO - 'tabpaint']}, 05:48:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:48:01 INFO - 'tests': ['a11yr', 05:48:01 INFO - 'ts_paint', 05:48:01 INFO - 'tpaint', 05:48:01 INFO - 'sessionrestore', 05:48:01 INFO - 'sessionrestore_no_auto_restore', 05:48:01 INFO - 'tabpaint']}, 05:48:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:48:01 INFO - 'tests': ['tsvgx', 05:48:01 INFO - 'tsvgr_opacity', 05:48:01 INFO - 'tart', 05:48:01 INFO - 'tscrollx', 05:48:01 INFO - 'cart']}, 05:48:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:48:01 INFO - 'tsvgr_opacity', 05:48:01 INFO - 'tart', 05:48:01 INFO - 'tscrollx', 05:48:01 INFO - 'cart']}, 05:48:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:01 INFO - 'talos_options': ['--disable-e10s'], 05:48:01 INFO - 'tests': ['tp5o']}, 05:48:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:01 INFO - 'tests': ['tp5o']}, 05:48:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:01 INFO - 'talos_options': ['--disable-e10s', 05:48:01 INFO - '--xperf_path', 05:48:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:48:01 INFO - 'tests': ['tp5n']}, 05:48:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:01 INFO - 'talos_options': ['--xperf_path', 05:48:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:48:01 INFO - 'tests': ['tp5n']}}, 05:48:01 INFO - 'talos.zip': {'path': '', 05:48:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:48:01 INFO - Running post-action listener: _resource_record_post_action 05:48:01 INFO - [mozharness: 2016-09-08 12:48:01.030459Z] Finished populate-webroot step (success) 05:48:01 INFO - [mozharness: 2016-09-08 12:48:01.030549Z] Running create-virtualenv step. 05:48:01 INFO - Running pre-action listener: _resource_record_pre_action 05:48:01 INFO - Running main action method: create_virtualenv 05:48:01 INFO - Creating virtualenv /builds/slave/test/build/venv 05:48:01 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 05:48:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:48:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:48:01 INFO - Using real prefix '/usr' 05:48:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:48:01 INFO - Installing distribute.............................................................................................................................................................................................done. 05:48:03 INFO - Installing pip.................done. 05:48:03 INFO - Return code: 0 05:48:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:48:03 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')]} 05:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:03 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')]} 05:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:03 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 0x1ad6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d97180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d953b0>, '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 0x1d962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d96740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', '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 05:48:03 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 05:48:03 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 05:48:03 INFO - Using env: {'DISPLAY': ':0', 05:48:03 INFO - 'HOME': '/home/cltbld', 05:48:03 INFO - 'LANG': 'en_US.UTF-8', 05:48:03 INFO - 'LANGUAGE': 'en_US:en', 05:48:03 INFO - 'LOGNAME': 'cltbld', 05:48:03 INFO - 'MAIL': '/var/mail/cltbld', 05:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:03 INFO - 'MOZ_NO_REMOTE': '1', 05:48:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:03 INFO - 'NO_EM_RESTART': '1', 05:48:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:03 INFO - 'PWD': '/builds/slave/test', 05:48:03 INFO - 'SHELL': '/bin/bash', 05:48:03 INFO - 'SHLVL': '1', 05:48:03 INFO - 'TERM': 'linux', 05:48:03 INFO - 'TMOUT': '86400', 05:48:03 INFO - 'USER': 'cltbld', 05:48:03 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', 05:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:03 INFO - '_': '/tools/buildbot/bin/python'} 05:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:03 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 05:48:03 INFO - Cleaning up... 05:48:03 INFO - Return code: 0 05:48:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:48:03 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')]} 05:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:03 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')]} 05:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:03 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 0x1ad6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d97180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d953b0>, '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 0x1d962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d96740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', '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 05:48:03 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 05:48:03 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 05:48:03 INFO - Using env: {'DISPLAY': ':0', 05:48:03 INFO - 'HOME': '/home/cltbld', 05:48:03 INFO - 'LANG': 'en_US.UTF-8', 05:48:03 INFO - 'LANGUAGE': 'en_US:en', 05:48:03 INFO - 'LOGNAME': 'cltbld', 05:48:03 INFO - 'MAIL': '/var/mail/cltbld', 05:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:03 INFO - 'MOZ_NO_REMOTE': '1', 05:48:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:03 INFO - 'NO_EM_RESTART': '1', 05:48:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:03 INFO - 'PWD': '/builds/slave/test', 05:48:03 INFO - 'SHELL': '/bin/bash', 05:48:03 INFO - 'SHLVL': '1', 05:48:03 INFO - 'TERM': 'linux', 05:48:03 INFO - 'TMOUT': '86400', 05:48:03 INFO - 'USER': 'cltbld', 05:48:03 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', 05:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:03 INFO - '_': '/tools/buildbot/bin/python'} 05:48:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:04 INFO - Downloading/unpacking psutil>=3.1.1 05:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:48:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:48:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:48:06 INFO - Installing collected packages: psutil 05:48:06 INFO - Running setup.py install for psutil 05:48:06 INFO - building 'psutil._psutil_linux' extension 05:48:06 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 05:48:06 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 05:48:06 INFO - building 'psutil._psutil_posix' extension 05:48:06 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 05:48:06 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 05:48:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:48:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:48:06 INFO - Successfully installed psutil 05:48:06 INFO - Cleaning up... 05:48:06 INFO - Return code: 0 05:48:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d97180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d953b0>, '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 0x1d962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d96740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', '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 05:48:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:48:06 INFO - Using env: {'DISPLAY': ':0', 05:48:06 INFO - 'HOME': '/home/cltbld', 05:48:06 INFO - 'LANG': 'en_US.UTF-8', 05:48:06 INFO - 'LANGUAGE': 'en_US:en', 05:48:06 INFO - 'LOGNAME': 'cltbld', 05:48:06 INFO - 'MAIL': '/var/mail/cltbld', 05:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:06 INFO - 'MOZ_NO_REMOTE': '1', 05:48:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:06 INFO - 'NO_EM_RESTART': '1', 05:48:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:06 INFO - 'PWD': '/builds/slave/test', 05:48:06 INFO - 'SHELL': '/bin/bash', 05:48:06 INFO - 'SHLVL': '1', 05:48:06 INFO - 'TERM': 'linux', 05:48:06 INFO - 'TMOUT': '86400', 05:48:06 INFO - 'USER': 'cltbld', 05:48:06 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', 05:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:06 INFO - '_': '/tools/buildbot/bin/python'} 05:48:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:48:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:48:08 INFO - Installing collected packages: mozsystemmonitor 05:48:08 INFO - Running setup.py install for mozsystemmonitor 05:48:08 INFO - Successfully installed mozsystemmonitor 05:48:08 INFO - Cleaning up... 05:48:08 INFO - Return code: 0 05:48:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:48:08 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')]} 05:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:08 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')]} 05:48:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:08 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 0x1ad6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d97180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d953b0>, '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 0x1d962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d96740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', '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 05:48:08 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 05:48:08 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 05:48:08 INFO - Using env: {'DISPLAY': ':0', 05:48:08 INFO - 'HOME': '/home/cltbld', 05:48:08 INFO - 'LANG': 'en_US.UTF-8', 05:48:08 INFO - 'LANGUAGE': 'en_US:en', 05:48:08 INFO - 'LOGNAME': 'cltbld', 05:48:08 INFO - 'MAIL': '/var/mail/cltbld', 05:48:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:08 INFO - 'MOZ_NO_REMOTE': '1', 05:48:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:08 INFO - 'NO_EM_RESTART': '1', 05:48:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:08 INFO - 'PWD': '/builds/slave/test', 05:48:08 INFO - 'SHELL': '/bin/bash', 05:48:08 INFO - 'SHLVL': '1', 05:48:08 INFO - 'TERM': 'linux', 05:48:08 INFO - 'TMOUT': '86400', 05:48:08 INFO - 'USER': 'cltbld', 05:48:08 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', 05:48:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:08 INFO - '_': '/tools/buildbot/bin/python'} 05:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:09 INFO - Downloading/unpacking jsonschema==2.5.1 05:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:48:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:11 INFO - Downloading functools32-3.2.3-2.tar.gz 05:48:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:48:11 INFO - warning: no files found matching '*.txt' 05:48:11 INFO - no previously-included directories found matching 'build' 05:48:11 INFO - no previously-included directories found matching 'dist' 05:48:11 INFO - no previously-included directories found matching '.git*' 05:48:11 INFO - Installing collected packages: jsonschema, functools32 05:48:11 INFO - Running setup.py install for functools32 05:48:11 INFO - warning: no files found matching '*.txt' 05:48:11 INFO - no previously-included directories found matching 'build' 05:48:11 INFO - no previously-included directories found matching 'dist' 05:48:11 INFO - no previously-included directories found matching '.git*' 05:48:11 INFO - Successfully installed jsonschema functools32 05:48:11 INFO - Cleaning up... 05:48:11 INFO - Return code: 0 05:48:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:48: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')]} 05:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48: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')]} 05:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:11 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 0x1ad6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d97180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d953b0>, '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 0x1d962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d96740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', '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 05:48:11 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 05:48:11 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 05:48:11 INFO - Using env: {'DISPLAY': ':0', 05:48:11 INFO - 'HOME': '/home/cltbld', 05:48:11 INFO - 'LANG': 'en_US.UTF-8', 05:48:11 INFO - 'LANGUAGE': 'en_US:en', 05:48:11 INFO - 'LOGNAME': 'cltbld', 05:48:11 INFO - 'MAIL': '/var/mail/cltbld', 05:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:11 INFO - 'MOZ_NO_REMOTE': '1', 05:48:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:11 INFO - 'NO_EM_RESTART': '1', 05:48:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:11 INFO - 'PWD': '/builds/slave/test', 05:48:11 INFO - 'SHELL': '/bin/bash', 05:48:11 INFO - 'SHLVL': '1', 05:48:11 INFO - 'TERM': 'linux', 05:48:11 INFO - 'TMOUT': '86400', 05:48:11 INFO - 'USER': 'cltbld', 05:48:11 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', 05:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:11 INFO - '_': '/tools/buildbot/bin/python'} 05:48:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:48:11 INFO - Cleaning up... 05:48:11 INFO - Return code: 0 05:48:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:48: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')]} 05:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48: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')]} 05:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:11 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 0x1ad6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d97180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d953b0>, '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 0x1d962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d96740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', '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 05:48:11 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 05:48:11 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 05:48:11 INFO - Using env: {'DISPLAY': ':0', 05:48:11 INFO - 'HOME': '/home/cltbld', 05:48:11 INFO - 'LANG': 'en_US.UTF-8', 05:48:11 INFO - 'LANGUAGE': 'en_US:en', 05:48:11 INFO - 'LOGNAME': 'cltbld', 05:48:11 INFO - 'MAIL': '/var/mail/cltbld', 05:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:11 INFO - 'MOZ_NO_REMOTE': '1', 05:48:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:11 INFO - 'NO_EM_RESTART': '1', 05:48:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:11 INFO - 'PWD': '/builds/slave/test', 05:48:11 INFO - 'SHELL': '/bin/bash', 05:48:11 INFO - 'SHLVL': '1', 05:48:11 INFO - 'TERM': 'linux', 05:48:11 INFO - 'TMOUT': '86400', 05:48:11 INFO - 'USER': 'cltbld', 05:48:11 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', 05:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:11 INFO - '_': '/tools/buildbot/bin/python'} 05:48:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:11 INFO - Downloading/unpacking blobuploader==1.2.4 05:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:14 INFO - Downloading blobuploader-1.2.4.tar.gz 05:48:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:48:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:48:14 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 05:48:14 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 05:48:14 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 05:48:14 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 05:48:14 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 05:48:14 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 05:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:48:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:48:15 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 05:48:15 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 05:48:15 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 05:48:15 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 05:48:15 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 05:48:15 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 05:48:15 INFO - Downloading docopt-0.6.1.tar.gz 05:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:48:15 INFO - Installing collected packages: blobuploader, requests, docopt 05:48:15 INFO - Running setup.py install for blobuploader 05:48:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:48:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:48:15 INFO - Running setup.py install for requests 05:48:16 INFO - Running setup.py install for docopt 05:48:16 INFO - Successfully installed blobuploader requests docopt 05:48:16 INFO - Cleaning up... 05:48:16 INFO - Return code: 0 05:48:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:16 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 0x1ad6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d97180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d953b0>, '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 0x1d962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d96740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', '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 05:48:16 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 05:48:16 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 05:48:16 INFO - Using env: {'DISPLAY': ':0', 05:48:16 INFO - 'HOME': '/home/cltbld', 05:48:16 INFO - 'LANG': 'en_US.UTF-8', 05:48:16 INFO - 'LANGUAGE': 'en_US:en', 05:48:16 INFO - 'LOGNAME': 'cltbld', 05:48:16 INFO - 'MAIL': '/var/mail/cltbld', 05:48:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:16 INFO - 'MOZ_NO_REMOTE': '1', 05:48:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:16 INFO - 'NO_EM_RESTART': '1', 05:48:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:16 INFO - 'PWD': '/builds/slave/test', 05:48:16 INFO - 'SHELL': '/bin/bash', 05:48:16 INFO - 'SHLVL': '1', 05:48:16 INFO - 'TERM': 'linux', 05:48:16 INFO - 'TMOUT': '86400', 05:48:16 INFO - 'USER': 'cltbld', 05:48:16 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', 05:48:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:16 INFO - '_': '/tools/buildbot/bin/python'} 05:48:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:48:16 INFO - Running setup.py (path:/tmp/pip-3Olayw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:48:16 INFO - Running setup.py (path:/tmp/pip-w1Hal0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:48:16 INFO - Running setup.py (path:/tmp/pip-2GNUUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:48:16 INFO - Running setup.py (path:/tmp/pip-qXgf9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:48:16 INFO - Running setup.py (path:/tmp/pip-bW5h9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:48:16 INFO - Running setup.py (path:/tmp/pip-tpnHPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:48:16 INFO - Running setup.py (path:/tmp/pip-DhztgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:48:16 INFO - Running setup.py (path:/tmp/pip-jtI2VY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:48:16 INFO - Running setup.py (path:/tmp/pip-mWpudZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:48:17 INFO - Running setup.py (path:/tmp/pip-3kRr2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:48:17 INFO - Running setup.py (path:/tmp/pip-9iC08p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:48:17 INFO - Running setup.py (path:/tmp/pip-NDUO0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:48:17 INFO - Running setup.py (path:/tmp/pip-Rf3_tF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:48:17 INFO - Running setup.py (path:/tmp/pip-iNyM29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:17 INFO - Running setup.py (path:/tmp/pip-bJpZi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:48:17 INFO - Running setup.py (path:/tmp/pip-KaFNSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:48:17 INFO - Running setup.py (path:/tmp/pip-OYrVbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:48:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:48:17 INFO - Running setup.py install for manifestparser 05:48:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:48:17 INFO - Running setup.py install for mozcrash 05:48:17 INFO - Running setup.py install for mozdebug 05:48:17 INFO - Running setup.py install for mozdevice 05:48:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:48:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:48:18 INFO - Running setup.py install for mozfile 05:48:18 INFO - Running setup.py install for mozhttpd 05:48:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:48:18 INFO - Running setup.py install for mozinfo 05:48:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:48:18 INFO - Running setup.py install for mozInstall 05:48:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:48:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:48:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:48:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:48:18 INFO - Running setup.py install for mozleak 05:48:18 INFO - Running setup.py install for mozlog 05:48:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:48:18 INFO - Running setup.py install for moznetwork 05:48:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:48:18 INFO - Running setup.py install for mozprocess 05:48:18 INFO - Running setup.py install for mozprofile 05:48:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:48:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:48:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:48:18 INFO - Running setup.py install for mozrunner 05:48:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:48:19 INFO - Running setup.py install for mozscreenshot 05:48:19 INFO - Running setup.py install for moztest 05:48:19 INFO - Running setup.py install for mozversion 05:48:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:48:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:48:19 INFO - Cleaning up... 05:48:19 INFO - Return code: 0 05:48:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48: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')]} 05:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48: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')]} 05:48:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:19 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 0x1ad6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d97180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d953b0>, '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 0x1d962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d96740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', '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 05:48:19 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 05:48:19 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 05:48:19 INFO - Using env: {'DISPLAY': ':0', 05:48:19 INFO - 'HOME': '/home/cltbld', 05:48:19 INFO - 'LANG': 'en_US.UTF-8', 05:48:19 INFO - 'LANGUAGE': 'en_US:en', 05:48:19 INFO - 'LOGNAME': 'cltbld', 05:48:19 INFO - 'MAIL': '/var/mail/cltbld', 05:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:19 INFO - 'MOZ_NO_REMOTE': '1', 05:48:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:19 INFO - 'NO_EM_RESTART': '1', 05:48:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:19 INFO - 'PWD': '/builds/slave/test', 05:48:19 INFO - 'SHELL': '/bin/bash', 05:48:19 INFO - 'SHLVL': '1', 05:48:19 INFO - 'TERM': 'linux', 05:48:19 INFO - 'TMOUT': '86400', 05:48:19 INFO - 'USER': 'cltbld', 05:48:19 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', 05:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:19 INFO - '_': '/tools/buildbot/bin/python'} 05:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:48:19 INFO - Running setup.py (path:/tmp/pip-uvb6w5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:48:19 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)) 05:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:48:19 INFO - Running setup.py (path:/tmp/pip-aiZfWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:48:19 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)) 05:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:48:19 INFO - Running setup.py (path:/tmp/pip-Nl55iT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:48:19 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)) 05:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:48:19 INFO - Running setup.py (path:/tmp/pip-cP0KGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:48:19 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)) 05:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:48:19 INFO - Running setup.py (path:/tmp/pip-gkj8Iw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:48:20 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)) 05:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:48:20 INFO - Running setup.py (path:/tmp/pip-ozNluy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:48:20 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)) 05:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:48:20 INFO - Running setup.py (path:/tmp/pip-uJ_3F9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:48:20 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)) 05:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:48:20 INFO - Running setup.py (path:/tmp/pip-cQH60s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:48:20 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)) 05:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:48:20 INFO - Running setup.py (path:/tmp/pip-a88Jrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:48:20 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)) 05:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:48:20 INFO - Running setup.py (path:/tmp/pip-QJIZbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:48:20 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)) 05:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:48:20 INFO - Running setup.py (path:/tmp/pip-Jz9SqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:48:20 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)) 05:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:48:20 INFO - Running setup.py (path:/tmp/pip-qO8Bwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:48:20 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)) 05:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:48:20 INFO - Running setup.py (path:/tmp/pip-I1vsqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:48:20 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)) 05:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:48:20 INFO - Running setup.py (path:/tmp/pip-Ou59FH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:48:20 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)) 05:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:20 INFO - Running setup.py (path:/tmp/pip-5cIey7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:20 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)) 05:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:48:20 INFO - Running setup.py (path:/tmp/pip-zHAlk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:48:20 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)) 05:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:48:20 INFO - Running setup.py (path:/tmp/pip-aN8F4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:48:20 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)) 05:48:20 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)) 05:48:20 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)) 05:48:20 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)) 05:48:20 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)) 05:48:20 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)) 05:48:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:22 INFO - Downloading blessings-1.6.tar.gz 05:48:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:48:22 INFO - Installing collected packages: blessings 05:48:22 INFO - Running setup.py install for blessings 05:48:22 INFO - Successfully installed blessings 05:48:22 INFO - Cleaning up... 05:48:23 INFO - Return code: 0 05:48:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:48:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:48:23 INFO - Reading from file tmpfile_stdout 05:48:23 INFO - Current package versions: 05:48:23 INFO - argparse == 1.2.1 05:48:23 INFO - blessings == 1.6 05:48:23 INFO - blobuploader == 1.2.4 05:48:23 INFO - docopt == 0.6.1 05:48:23 INFO - functools32 == 3.2.3-2 05:48:23 INFO - jsonschema == 2.5.1 05:48:23 INFO - manifestparser == 1.1 05:48:23 INFO - mozInstall == 1.12 05:48:23 INFO - mozcrash == 0.17 05:48:23 INFO - mozdebug == 0.1 05:48:23 INFO - mozdevice == 0.48 05:48:23 INFO - mozfile == 1.2 05:48:23 INFO - mozhttpd == 0.7 05:48:23 INFO - mozinfo == 0.9 05:48:23 INFO - mozleak == 0.1 05:48:23 INFO - mozlog == 3.3 05:48:23 INFO - moznetwork == 0.27 05:48:23 INFO - mozprocess == 0.23 05:48:23 INFO - mozprofile == 0.28 05:48:23 INFO - mozrunner == 6.12 05:48:23 INFO - mozscreenshot == 0.1 05:48:23 INFO - mozsystemmonitor == 0.3 05:48:23 INFO - moztest == 0.7 05:48:23 INFO - mozversion == 1.4 05:48:23 INFO - psutil == 3.1.1 05:48:23 INFO - requests == 1.2.3 05:48:23 INFO - wsgiref == 0.1.2 05:48:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:23 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 0x1ad6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d97180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d953b0>, '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 0x1d962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d96740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', '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 05:48:23 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 05:48:23 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 05:48:23 INFO - Using env: {'DISPLAY': ':0', 05:48:23 INFO - 'HOME': '/home/cltbld', 05:48:23 INFO - 'LANG': 'en_US.UTF-8', 05:48:23 INFO - 'LANGUAGE': 'en_US:en', 05:48:23 INFO - 'LOGNAME': 'cltbld', 05:48:23 INFO - 'MAIL': '/var/mail/cltbld', 05:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:23 INFO - 'MOZ_NO_REMOTE': '1', 05:48:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:23 INFO - 'NO_EM_RESTART': '1', 05:48:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:23 INFO - 'PWD': '/builds/slave/test', 05:48:23 INFO - 'SHELL': '/bin/bash', 05:48:23 INFO - 'SHLVL': '1', 05:48:23 INFO - 'TERM': 'linux', 05:48:23 INFO - 'TMOUT': '86400', 05:48:23 INFO - 'USER': 'cltbld', 05:48:23 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', 05:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:23 INFO - '_': '/tools/buildbot/bin/python'} 05:48:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:23 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)) 05:48:23 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)) 05:48:23 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)) 05:48:23 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)) 05:48:23 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)) 05:48:23 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)) 05:48:23 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)) 05:48:23 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)) 05:48:23 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)) 05:48:23 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)) 05:48:23 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)) 05:48:23 INFO - Cleaning up... 05:48:23 INFO - Return code: 0 05:48:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:23 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 0x1ad6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d97180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d953b0>, '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 0x1d962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d96740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', '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 05:48:23 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 05:48:23 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 05:48:23 INFO - Using env: {'DISPLAY': ':0', 05:48:23 INFO - 'HOME': '/home/cltbld', 05:48:23 INFO - 'LANG': 'en_US.UTF-8', 05:48:23 INFO - 'LANGUAGE': 'en_US:en', 05:48:23 INFO - 'LOGNAME': 'cltbld', 05:48:23 INFO - 'MAIL': '/var/mail/cltbld', 05:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:23 INFO - 'MOZ_NO_REMOTE': '1', 05:48:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:23 INFO - 'NO_EM_RESTART': '1', 05:48:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:23 INFO - 'PWD': '/builds/slave/test', 05:48:23 INFO - 'SHELL': '/bin/bash', 05:48:23 INFO - 'SHLVL': '1', 05:48:23 INFO - 'TERM': 'linux', 05:48:23 INFO - 'TMOUT': '86400', 05:48:23 INFO - 'USER': 'cltbld', 05:48:23 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', 05:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:23 INFO - '_': '/tools/buildbot/bin/python'} 05:48:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:48:23 INFO - Cleaning up... 05:48:23 INFO - Return code: 0 05:48:23 INFO - Running post-action listener: _resource_record_post_action 05:48:23 INFO - Running post-action listener: _start_resource_monitoring 05:48:23 INFO - Starting resource monitoring. 05:48:23 INFO - [mozharness: 2016-09-08 12:48:23.742515Z] Finished create-virtualenv step (success) 05:48:23 INFO - [mozharness: 2016-09-08 12:48:23.742943Z] Running install step. 05:48:23 INFO - Running pre-action listener: _resource_record_pre_action 05:48:23 INFO - Running main action method: install 05:48:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:48:23 INFO - Reading from file tmpfile_stdout 05:48:23 INFO - Detecting whether we're running mozinstall >=1.0... 05:48:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:48:24 INFO - Reading from file tmpfile_stdout 05:48:24 INFO - Output received: 05:48:24 INFO - Usage: mozinstall [options] installer 05:48:24 INFO - Options: 05:48:24 INFO - -h, --help show this help message and exit 05:48:24 INFO - -d DEST, --destination=DEST 05:48:24 INFO - Directory to install application into. [default: 05:48:24 INFO - "/builds/slave/test"] 05:48:24 INFO - --app=APP Application being installed. [default: firefox] 05:48:24 INFO - mkdir: /builds/slave/test/build/application 05:48:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:48:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:48:40 INFO - Reading from file tmpfile_stdout 05:48:40 INFO - Output received: 05:48:40 INFO - /builds/slave/test/build/application/firefox/firefox 05:48:40 INFO - Running post-action listener: _resource_record_post_action 05:48:40 INFO - [mozharness: 2016-09-08 12:48:40.140438Z] Finished install step (success) 05:48:40 INFO - [mozharness: 2016-09-08 12:48:40.140713Z] Running run-tests step. 05:48:40 INFO - Running pre-action listener: _resource_record_pre_action 05:48:40 INFO - Running main action method: run_tests 05:48:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:48:40 INFO - Python 2.7.3 05:48:40 INFO - Return code: 0 05:48:40 INFO - grabbing minidump binary from tooltool 05:48: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')]} 05:48:40 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 0x1d953b0>, '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 0x1d962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d96740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:48:40 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 05:48:40 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 05:48:40 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 05:48:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:48:40 INFO - Return code: 0 05:48:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:48:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:48:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:48:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:48:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:48:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 05:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-068 --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 05:48:40 INFO - Using env: {'DISPLAY': ':0', 05:48:40 INFO - 'HOME': '/home/cltbld', 05:48:40 INFO - 'LANG': 'en_US.UTF-8', 05:48:40 INFO - 'LANGUAGE': 'en_US:en', 05:48:40 INFO - 'LOGNAME': 'cltbld', 05:48:40 INFO - 'MAIL': '/var/mail/cltbld', 05:48:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:48:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:40 INFO - 'MOZ_NO_REMOTE': '1', 05:48:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:48:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:40 INFO - 'NO_EM_RESTART': '1', 05:48:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:40 INFO - 'PWD': '/builds/slave/test', 05:48:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:48:40 INFO - 'SHELL': '/bin/bash', 05:48:40 INFO - 'SHLVL': '1', 05:48:40 INFO - 'TERM': 'linux', 05:48:40 INFO - 'TMOUT': '86400', 05:48:40 INFO - 'USER': 'cltbld', 05:48:40 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019', 05:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:40 INFO - '_': '/tools/buildbot/bin/python'} 05:48:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 05:48:40 INFO - mozversion application_buildid: 20160908025615 05:48:40 INFO - mozversion application_changeset: 46c6e8bb7f6f6d7281baa761cc931ccd46feca5d 05:48:40 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:48:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:48:40 INFO - mozversion application_name: Firefox 05:48:40 INFO - mozversion application_remotingname: firefox-dev 05:48:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:48:40 INFO - mozversion application_vendor: Mozilla 05:48:40 INFO - mozversion application_version: 50.0a2 05:48:40 INFO - mozversion platform_buildid: 20160908025615 05:48:40 INFO - mozversion platform_changeset: 46c6e8bb7f6f6d7281baa761cc931ccd46feca5d 05:48:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:48:40 INFO - mozversion platform_version: 50.0a2 05:48:40 INFO - using testdate: 1473338920 05:48:40 INFO - actual date: 1473338920 05:48:40 INFO - starting webserver on 'localhost:52168' 05:48:40 INFO - SUITE-START | Running 2 tests 05:48:40 INFO - TEST-START | dromaeo_css 05:48:40 INFO - operating with platform_type : linux_ 05:48:40 INFO - Initialising browser for dromaeo_css test... 05:48:40 INFO - TEST-INFO | started process 24965 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzoK5_z/profile http://localhost:52168/getInfo.html) 05:48:44 INFO - PROCESS | 24965 | __metrics Screen width/height:1600/1200 05:48:44 INFO - PROCESS | 24965 | colorDepth:24 05:48:44 INFO - PROCESS | 24965 | Browser inner width/height: 1024/697 05:48:44 INFO - PROCESS | 24965 | __metrics 05:48:45 INFO - PROCESS | 24965 | 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" 05:48:45 INFO - PROCESS | 24965 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:48:46 INFO - TEST-INFO | 24965: exit 0 05:48:46 INFO - Browser initialized. 05:48:46 INFO - Running cycle 1/1 for dromaeo_css test... 05:48:46 INFO - TEST-INFO | started process 25056 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzoK5_z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:48:58 INFO - PROCESS | 25056 | RSS: Main: 146395136 05:48:58 INFO - PROCESS | 25056 | 05:49:49 INFO - PROCESS | 25056 | 1473338989401 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] 05:51:58 INFO - PROCESS | 25056 | Cycle 1(1): loaded http://localhost:52168/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52168/tests/dromaeo/cssquery-ext.html) 05:51:59 INFO - PROCESS | 25056 | RSS: Main: 281341952 05:51:59 INFO - PROCESS | 25056 | 05:55:02 INFO - PROCESS | 25056 | Cycle 1(1): loaded http://localhost:52168/tests/dromaeo/cssquery-ext.html (next: http://localhost:52168/tests/dromaeo/cssquery-jquery.html) 05:55:02 INFO - PROCESS | 25056 | RSS: Main: 231981056 05:55:02 INFO - PROCESS | 25056 | 05:58:04 INFO - PROCESS | 25056 | Cycle 1(1): loaded http://localhost:52168/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52168/tests/dromaeo/cssquery-mootools.html) 05:58:05 INFO - PROCESS | 25056 | RSS: Main: 226693120 05:58:05 INFO - PROCESS | 25056 | 06:01:07 INFO - PROCESS | 25056 | Cycle 1(1): loaded http://localhost:52168/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52168/tests/dromaeo/cssquery-prototype.html) 06:01:07 INFO - PROCESS | 25056 | RSS: Main: 175194112 06:01:07 INFO - PROCESS | 25056 | 06:04:10 INFO - PROCESS | 25056 | Cycle 1(1): loaded http://localhost:52168/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52168/tests/dromaeo/cssquery-yui.html) 06:04:11 INFO - PROCESS | 25056 | RSS: Main: 177680384 06:04:11 INFO - PROCESS | 25056 | 06:07:13 INFO - PROCESS | 25056 | Cycle 1(1): loaded http://localhost:52168/tests/dromaeo/cssquery-yui.html (next: http://localhost:52168/tests/dromaeo/cssquery-dojo.html) 06:07:14 INFO - PROCESS | 25056 | RSS: Main: 240541696 06:07:14 INFO - PROCESS | 25056 | 06:07:14 INFO - PROCESS | 25056 | __start_tp_report 06:07:14 INFO - PROCESS | 25056 | _x_x_mozilla_page_load 06:07:14 INFO - PROCESS | 25056 | _x_x_mozilla_page_load_details 06:07:14 INFO - PROCESS | 25056 | |i|pagename|runs| 06:07:14 INFO - PROCESS | 25056 | |0;dojo.html;3238.158078899486;3269.280758233188;3269.5422640830275;3283.8522266498007;3323.135984643992;5637.379888212294;5752.165935939292;5757.1652110444065;5773.682447465388;5796.362400135987;5705;5776.624519406236;5776.711164441772;5784.103463963082;5801.564882633798;5021.874453138665;5091.592672586203;5166.844994650167;5226.05930932432;5247.422783493813;5138.254953031808;5188.740562971855;5191.714455704935;5191.844244672647;5195.6622819516615;5098.439171691111;5134.358205224347;5135.8202462913805;5167.250748641445;5184.403793563751;5253.7110458924935;5258.579313654898;5267.130923397634;5271.314729085233;5283.762230699628;5018.698878067327;5080.1363768159135;5118.846434606968;5161.4322424533275;5176.378834539879;5561.332640083216;5588.050031494656;5589.133684278943;5592.7762889484375;5603.327600687903;6107.4503294703245;6120.663363515005;6121.816345509642;6122.8775424491705;6147.846303842395;5899.380565040695;6038.788642397539;6053.616610909228;6110.480609148228;6172.722227499773;5670.177824215507;5710.343310519302;5737.053386191274;5748.626339287933;5751.511121554715;6247.468965137923;6251.906221406683;6252.030935205051;6261.279952805486;6270;5797.536197104221;5927.4665280125;5953.107033944856;5954.732037058343;6067.54493412987;6123.020316749299;6125.234345706786;6132.78385080153;6139.2325959255095;6144.446999770043;6032.125341825411;6088.4215999479975;6088.512918966472;6099.146119543268;6127.142200091991;5929.436703513245;5974.910376344358;6013.248343957005;6014.218151640259;6102.115193297036;4608.101420223092;4654.418197725285;4654.953450465519;4693.577578017995;4697.966447381571;4132.91734165321;4155.667546596265;4163.3130533461635;4164.562720914321;4188;5657.57568182388;5796.768129274866;5851.093080572518;5865.501432378211;5867.882642347129;6173.536984726074;6211.81364559064;6212.875742485125;6215.06773983897;6218.409251121137;5812.964243603229;5822.854428639318;5835.941640583625;5863.413658634103;5907.3797251288;4958.8760280993265;5052.545270925634;5084.8220312288895;5122.692638441706;5166.173412254021;6367.267764207083;6367.777127800505;6368.044793281045;6372.617642941438;6386.521010924199;6117.388261174025;6155.384461553809;6175.166352542349;6184.319724830355;6185.659788711664;6141.8464538385115;6268.279147897868;6288.842778930563;6334.746610135543;6391.265004524539;6096.786612468542;6113.57204995646;6114.996965385453;6116.061877111602;6120.812855101138;6151.056559034736;6198.783042593487;6200.937990620036;6211.689415529115;6219.813405597839;5780.884382312414;5975.850603734942;5999.4900433463645;6038.486728628116;6086.391360863879;6050.364711705208;6051.394860513913;6053.545984051302;6059.848503787441;6085.6348619083;5946.108083787293;5954.315253745702;5984.58107932458;5994.3705910878725;6017.638941663514;5789.507891829073;5911.793087241926;5951.047832346804;5993.67034813072;6082.9695851520455;5864.501517371071;5871.383504732113;5872.265966754156;5879.029960056543;5898.20374249488 06:07:14 INFO - PROCESS | 25056 | |1;ext.html;583.5098517245513;645.4126744662357;664.3423011218891;666.450178602653;668.4585485756537;45275.77362113189;46646;46827.063458730845;51049;51133;48480.27279590809;48690.26964595534;48725.26912096321;49090.75454622722;49100;8468.66125354988;9221.446713197212;9349.485778282191;9365.250779937589;10967.122630189566;10774.892251077466;11210.831837522444;11218.66344009681;11218.831717524203;11231.32612043276;11191.104711623091;11210;11220.326780393203;11230.045446137088;11292.548298068068;11078.501467433944;11121.388323642197;11132.443377831116;11303.547858085667;11315.321080735182;11115.944420277847;11187.216894817366;11192;11193.720156996058;11205.551777928873;44776.55223447757;45083.323750143776;45204.77397613024;45281.09437811258;45306;3561.198730285691;3578.58846232684;3690.815459227028;3739.942386013692;3939.4878665773267;34945.65054349449;35362.29275414503;35445;35498;35601;4737.73942433166;4800.135975524404;4807.423109226916;4835.44392394872;4888.755562221874;382769;385306;386640.066799667;388045;388424;62598.37401626017;62982;63084;63113.684431577545;63291;39355.40966885499;40234.195316093515;40389.19221615551;43125;43138;17004.319827206775;17128.14359282031;17143.571410714583;17173.227204775816;17289.65420691579;11188.944055279671;12694.42875070624;12888.291143987171;12907.935460322638;12917.806232906514;20810.375688729364;20903.8954805225;20990;21024.474388140417;21094.367168984954;7373.262673732691;7562.882392929989;7630.847383052308;7685.770276755692;7713.730019449293;1090.3022065877844;1115.5091759625739;1139.760650263454;1194.8805119488156;1202.5129822421934;33720.32559348799;36259.274814503566;36365.63634363622;36414.453783193276;36485.08787280286;1176.5352685689104;1201.897838683704;1211.0916812390708;1215.0826126274605;1244.234795600699;18815.81184188168;18903;18908.44912427656;19090.2363905445;19109.904450477938;660436;666439;673486;673612;679003;65626;66160.33839661638;66312.6684366575;66358.66820665865;66366.66816665983;1318.756030134428;1434.619825746178;1457.380613239369;1459.408939379553;1490.7316682997164;31380.372392551566;32943.50584741279;33012.339753205284;33054.33891322112;33163.17092072718;55640;56115.71942140263;56235.71882140563;56363.71818140883;56449.71775141098;462593;463066;464303;464750;465174;31023.53464697986;31332.216694582818;32207;32295.51556726604;33739;54306.72846635742;54434.45565544452;55092.724536378664;55264.72367638136;55320;45200;45708.542914570426;45943.540564593925;46340.30489542592;47037.52962470332;44806.77596611996;45806.31290530712;46178.53821461742;46287.81481297421;47046.294305586125;45350.8754456906;45434.31848522209;46385.30422043739;46786.532134678215;47527.52472475369;37357.15811063774;40058.59941400549;40243.3963490542;40806.38790418087;40985.38521922114;30413.594901911783;30553.388932220787;30752.846235768677;30819.383612328078;30963.380732384776 06:07:14 INFO - PROCESS | 25056 | |2;jquery.html;384.20469627870284;401.8774273846478;410.46229439434336;413.1159319057218;422.48034917052024;16426.507204783844;20148.39554813358;20319.085641146146;20527.589448211027;21039.158433662637;20717.68923466149;20895.119439250466;21085.68371474429;21248.36254912351;21287.787122128775;5853.531717462603;6027.668478233717;6062.181605483247;6062.878742425149;6096.695165241742;4453.064856380164;5824.475797178253;5870.530357571405;5874.647521148723;6431.196100487439;6616.20605527335;6764.3235676432205;6770.8307292317595;6771.5937043777285;6772.932270677279;5573.749181286831;5764.365919748845;5784.161296611967;5849.590528662995;6769.458443324522;5811.215485909433;5814.941850581482;5869.207656966298;5900.76396944126;5939.821805345847;20724.792752072437;24437.144699935594;24558.263252102464;24879;25115.62326565103;2418.661387405765;2478.157426475007;2518.735532769051;2527.166035208377;3138.419392412411;10760.139188864872;10870.782584348268;11502.597409090726;11931.582394616147;13287;2027.2701827342146;2092.4141240452695;2147;2151.3653472225574;2155.8490905636613;83638.16361836425;87090;87730.56134719285;89477;91429;11096.944515277533;11175.38535380562;11434.485448154854;11930.22453540517;11996.220245684002;15441.459548915958;15460.613484662974;15494.690106198039;15529.534113976599;15543.84456155424;7607.239276072459;7747;7750.412467449863;7792.064952205772;8156.632951517196;7284.599347035858;7414.518056326321;7507.812304692427;7674.923250767532;7902.723404680809;4629.907401852011;4785.3300537924715;4849.733264670477;4945.406551213878;5004.874878128076;2038.3579172560596;2051.2307884543297;2078.303768237657;2078.958420831575;2123.2886982860914;976.1653786012856;1008.8890222075565;1039.6880935719255;1055.7888422315568;1089.7057794395469;20519.794802051787;20788.1684732612;21230.575388492143;21280.893595531925;21313.786862131492;1040.1730624153815;1051.3271506236015;1069.9251052426255;1088.5319312695458;1104.0560320925654;11300.491477883515;11641.941790290994;11677.357745324089;11686.298822070703;11839.88160118405;569278;577258;591022;611190;618805;97988.51005745068;103340;103996;106814;107088.4645576767;1023.81059503992;1032.0917592518535;1063.4948399510326;1068.4444089073793;1079.3416016230094;18702.6259474807;18922.810771892106;19096.618067638847;19131.617367652292;19259.61480770349;32542;35439.64560354467;45194.54805451904;45194.77402612966;45609.54390456053;62847;64295.67852160709;64524.35475645184;65891;66571;8725.956370218108;8979.371443999118;9082.137196966278;9086.36395452312;9230.907690923004;13180.209187448783;13460.326983650582;13660.385282662106;13728.443668938327;14166.291685415481;12801.743965120831;12846.293453860128;12908.41912113976;13326.533571324957;13469.595912122651;12446.751064978469;12621.242725436136;12734.490620375082;12738.68153296175;12997.935010324887;12422.813657794959;12991.610251692464;13021.934890325489;13357.090807454548;13362.866371336551;9453.385529940531;9519;9593.472359020321;9625.32622716403;9636.566354514203;10831.945840270748;11931.940340298243;12664.6833829155;13005.154664946747;13081.869181308066 06:07:14 INFO - PROCESS | 25056 | |3;mootools.html;1429.363933049792;1523.268830961139;1550.6898620275945;1742.520806778136;1878.9812101878977;8857.114288571147;9262.351635385527;9357.625694972201;9632.422054676725;9640.084192001748;9444.622215111405;9552.426854388741;9587.185089267403;9596.904030959688;9617.230621550276;2485.291691867818;2501.462185630089;2506.6741323627903;2508.147229941819;2517.25740906433;2447.596146635803;2504.098524531167;2505.0355613089;2508.7114981777054;2517.2322441655288;2454.582720937436;2464.2607217834666;2474.7525247475287;2475.0347364527847;2479.838810477313;2390.988045059781;2402.327348342467;2423.8303318767694;2465.6917885264343;2474.3195621204313;2407.723111842143;2426.551088048717;2437.061731233488;2438.2563318187945;2449.5835707929696;9387.812243755156;9390.436573805593;9472.147506724428;9482.146606805352;9549.379290346105;5633.070543360341;5861;5873.85315367115;5877.588568800185;5878.61788983719;3557.51973483579;3623.0398944279787;3675.1363429594026;3713.1830997180846;3723.9813800930824;1186.1993154620682;1205.4515195585986;1210.1589395370222;1214.8117041858527;1229.643403413009;13393.06248562601;13430.529931452353;13437.932810335984;13451.461941522328;13466.528671496451;4881.585065269421;4916.410030796327;4952.257161425743;5029.949700502948;5123.436421993578;10522.263441559018;10523.368597884151;10527.105196058268;10538.156947444186;10544.209184311054;5703.230063941398;5744.396838331999;5755.942440575541;5761.510271626875;5761.53907687384;4721.504242054603;4735.431748190239;5276.3932147802725;5369.919451208235;5398.379186393537;4248.532661407243;4271.31658934569;4271.743695378287;4281.914361712748;4323.610875021201;1284.4284293489306;1326.257295914291;1328.4885319152197;1333.113479536117;1335.038772084098;1001.8897921228659;1055.8574592429923;1080.0657431322006;1087.1683162380784;1087.505185140759;6576.671166441659;6583.473322134218;6635.800925972132;6799.932000680029;6828.146481689789;492.7979528393413;496.6225668491972;503.3657591434745;506.9138246498104;507.9212722028092;4416.271315232951;4681.274402467623;4703.294505824086;4775.617950563947;4828.251620998681;11704.824427633592;12187.817182742267;12199.878001219875;12223.877761222451;12226.633201003984;11115.333080015063;11534.653960381202;11593.072554195644;11915.88084119165;12073.879261207276;8554.44396114263;8672.56637168139;8715.956420217732;8746.256568191648;8763.342749293828;6544.411003009752;6753.493487988322;6847.828804279933;6908.3091690830515;6989.231184569591;10901.83647245276;10951.45242737876;10979.83530247031;11054.557817687202;11160.944195279297;9729.270304727032;10646.73383165427;10964.122870170208;11347.262427942158;11403;8047.758567242992;8366.832663346577;8801.295896328407;8806.779830504289;8806.955965220133;9188;9432.575534101119;9454;9514.048595140708;9532.714018579454;8217.30152937007;8355.95822020886;8376.246137847624;8492.74521764348;8551;8126.065502467292;8128.593570321342;8192.54940978252;8267.503949763033;8345.248927596303;6984.965075174795;7153.928460715326;7448.366888814511;7500.099988001475;7512.661930213044;5432.320959880015;5531.640443371168;5572.582056345709;5657.43425657431;5716.79991200306;5437.510624043855;5438.075527160313;5535.11438169907;5561.6663000220115;5580.023495888122 06:07:14 INFO - PROCESS | 25056 | |4;prototype.html;354.6914184659347;355.61593479042637;356.9482425048368;357.75315032627486;360.0781998084903;5532.972335138324;5542.362628297737;5544.362398324194;5557.8054768083075;5565.972170139144;5453.64551304166;5469.753861076261;5495.477929596692;5495.862603434916;5496.395396506381;3844.384898416254;4030.214108248885;4274.743515389071;4323.351497275403;4329.766016685246;4281.807318670667;4285.828566857322;4298.892527686801;4306.35404689296;4322.935155972663;4078.143589846136;4268.146370725852;4276.401303817468;4276.6792490563175;4291.592298731615;4201.159768046403;4243.618074373306;4285.3786201000685;4287.142571485684;4293.874532106596;3814.771113733185;3814.9809250953567;3815.5993620669983;3822.9808850955847;3826.5408151022057;5188.455212202702;5292.68243905363;5317.388500322474;5317.8936421271355;5321.813736519242;4896.804127834882;4913.877153071202;4929.260610908356;4937.728424936627;4961.801527938878;5603.299587551556;5638.154276858463;5651.4348565143155;5663.461971112738;5674.914876276859;4108.897277568055;4191.811368488426;4214.304639734441;4219.599138081878;4224.704270701052;5595.916061259084;5766.394528574481;5901.734421951022;5912.674802885839;5917.940820591782;4288.678349123828;4304.332828411601;4309.525952145262;4317.352397140454;4317.892052698708;4364.345348197732;4367.837920229444;4370.125974805052;4371.846985355561;4371.912561748747;4809.639277054235;4905.043516514245;4927.7536123193695;4935.790731270789;4937.136001199776;5682.147677848273;5694.9430505695245;5707.400722924116;5726.713664316768;5746.252987111732;1491.701659668049;1511;1517.8937474376908;1523.7333466643292;1523.9695206096037;931.8165929269925;974.1573538888811;977.8093271811929;978.9118979291898;992.573193526776;4917.311576379309;4926.482719314492;4943.442815513102;4949.876253093701;4984.956167971114;5133.640645154804;5141.377897272418;5167.664101833444;5176.7411629418375;5188.948110518922;4635.884102897455;4898.363212782316;4939.46876468296;4958.9504104959215;4973.7015779052645;2744.051723276456;2751.6697996240177;2752.0918097027934;2760.6687197536426;2768.820026698258;5292.232626269247;5302.363716353985;5306.673331667083;5322.520973112439;5345.973270133624;4971.254311853251;5185.714785686823;5215.556677682363;5222.503862133091;5262.421133675292;5289.841304760786;5298.761555730079;5303.0719624066405;5312.282841816305;5318.73406329682;5113.718745469035;5126.641135120506;5139.254808052886;5176.741162941913;5184.870378240575;4854.611631069435;5052.520010598987;5074.974625126851;5080.517350851663;5102.974485127551;5334.919976200283;5859.589828711949;5916.171735957056;6060.909086363797;6095.817125486243;4292;4293.763842988666;4294.506131794758;4300.978495107504;4309.288967320357;5706.457886500776;5860.970695146668;5869.589128760946;5872;5886;5680.6591604503865;5702.749432823417;5752;5763.682997435014;5803.47768700819;5690.321355200309;5730.799422020376;5744.971275143597;5776.740046697824;5792.304923409218;5417.945820541744;5668.773249069991;5668.959174697188;5717.7141142941855;5727.6849773262875;5785.623934444247;5805.854853628524;5821.592488525763;5826.213461182856;5828.213191219131;5487.670739755627;5683;5704.80033198836;5721.628094173865;5740.282464691913 06:07:14 INFO - PROCESS | 25056 | |5;yui.html;799.9280064794167;816.1756625807933;831.5343407691693;848.1518481518482;852.8635418333066;15418.152001639904;16051.197440127991;16892.662146757073;16937.237824297907;17086.401975930847;15863.127527985956;15931;15963.361465541364;16005.519834404957;16030.679386412265;721.6932803558493;842.7387509871928;844.7592436155682;849.087231226431;849.3587341557115;856.9143085691443;860.5525126933991;862.3015357560365;862.4221771413137;863.0075413274722;916.4821875640275;917.8531434970406;919.9816003679922;920.8987011428737;921.2491819167391;749.8425330680564;890.6793554320471;900.6307413960278;905.42053086025;910.0671811393308;674.6289540752615;674.8312921769558;674.9392554670103;675.1021141881287;675.5271310082912;15056.924715376463;15095.849041509555;15143.848561514355;15145.621359466102;15147.924260378737;15359.923200384039;15563.455279065178;15741.606459838596;15986.44047458345;16180.514584562481;13645.249511276876;13669;14001.369938352798;14017.158970461805;14534;4494.8202071917085;4628.143793398232;4740.644451666139;4745.145873742725;4783.139034973703;68760;69434.65282673555;69721.65139174322;82720;84591;12843.293618850867;12890.548830790878;12930.224186548838;13109.213447193199;13336.866631333562;22965.54068918646;22982.195623153108;22991.195308164133;22991.195308164133;23067.07731690747;9418.481983490974;9537.952310238543;9572.521373931275;9651.227901767841;9835.901640983499;5098.847034588968;5220.3077014670735;5287.9735601321745;5332.68003919766;5394.78420863169;4014.297497937849;4158.397032430341;4171.144915292358;4172.374143878443;4177.853775117917;665.2250128600224;670.189071223815;678.4402867634221;687.1067612104245;697.9267176946449;742.0057123454596;771.4676872957641;798.1021350980147;822.1860358245294;849.5412477262332;10435.478226088666;10659.307145035547;10738.087262582612;11166.274192177483;11312.547498100148;716.5951237550767;764.4686942574968;773.2460850670551;812.8780682897612;814.4258297899967;4908.754562271872;4995.150824359867;5138.126518491866;5157.252198431282;5166.741662916839;95270.0472995275;129317;132952;139914.30042849923;142217;109831;112668.43665781783;114838;115422;116590;75401;80242;87755;89442.03090551833;89796.5510172458;10880.945595271973;10915.563377464812;10940.671779846618;11432.314061156358;11439.771204576029;52003.21995170151;52172.217416739535;52598.47401525936;53201;53325;74858;75618.62190689011;77555.61222193853;78444.60777696075;79413;13846.584602461942;14377.928110359382;14404.20776857241;14507;14548.981571289909;15876.206189690238;16091.195440228175;16416.83583164201;16817.747733784247;17261.223244953755;14430.927845361126;14511.927440363153;15107.32017059213;17188.82811171872;17201.397951071653;14207.715845682822;14918.627034324229;15146.189487138485;15519.844801552292;15679.216039198223;13252.933735331262;13493.865061349654;15166.393344266548;15315.234238287818;15418.768718469008;10927.83608245861;12208.267503949792;12323.322217278135;12366;12823.230606163661;12654.493820247455;12852.935735321264;13193.93403033017;13304.733905322033;13364.264965426606 06:07:14 INFO - PROCESS | 25056 | __end_tp_report 06:07:14 INFO - PROCESS | 25056 | __start_cc_report 06:07:14 INFO - PROCESS | 25056 | _x_x_mozilla_cycle_collect,1134 06:07:14 INFO - PROCESS | 25056 | __end_cc_report 06:07:14 INFO - PROCESS | 25056 | __startTimestamp1473340034013__endTimestamp 06:07:14 INFO - PROCESS | 25056 | 06:07:14 INFO - PROCESS | 25056 | ------- Summary: start ------- 06:07:14 INFO - PROCESS | 25056 | Number of tests: 6 06:07:14 INFO - PROCESS | 25056 | 06:07:14 INFO - PROCESS | 25056 | [#0] dojo.html Cycles:165 Average:5675.36 Median:5899.38 stddev:662.56 (11.2%) stddev-sans-first:636.41 06:07:14 INFO - PROCESS | 25056 | Values: 3238.2 3269.3 3269.5 3283.9 3323.1 5637.4 5752.2 5757.2 5773.7 5796.4 5705.0 5776.6 5776.7 5784.1 5801.6 5021.9 5091.6 5166.8 5226.1 5247.4 5138.3 5188.7 5191.7 5191.8 5195.7 5098.4 5134.4 5135.8 5167.3 5184.4 5253.7 5258.6 5267.1 5271.3 5283.8 5018.7 5080.1 5118.8 5161.4 5176.4 5561.3 5588.1 5589.1 5592.8 5603.3 6107.5 6120.7 6121.8 6122.9 6147.8 5899.4 6038.8 6053.6 6110.5 6172.7 5670.2 5710.3 5737.1 5748.6 5751.5 6247.5 6251.9 6252.0 6261.3 6270.0 5797.5 5927.5 5953.1 5954.7 6067.5 6123.0 6125.2 6132.8 6139.2 6144.4 6032.1 6088.4 6088.5 6099.1 6127.1 5929.4 5974.9 6013.2 6014.2 6102.1 4608.1 4654.4 4655.0 4693.6 4698.0 4132.9 4155.7 4163.3 4164.6 4188.0 5657.6 5796.8 5851.1 5865.5 5867.9 6173.5 6211.8 6212.9 6215.1 6218.4 5813.0 5822.9 5835.9 5863.4 5907.4 4958.9 5052.5 5084.8 5122.7 5166.2 6367.3 6367.8 6368.0 6372.6 6386.5 6117.4 6155.4 6175.2 6184.3 6185.7 6141.8 6268.3 6288.8 6334.7 6391.3 6096.8 6113.6 6115.0 6116.1 6120.8 6151.1 6198.8 6200.9 6211.7 6219.8 5780.9 5975.9 5999.5 6038.5 6086.4 6050.4 6051.4 6053.5 6059.8 6085.6 5946.1 5954.3 5984.6 5994.4 6017.6 5789.5 5911.8 5951.0 5993.7 6083.0 5864.5 5871.4 5872.3 5879.0 5898.2 06:07:14 INFO - PROCESS | 25056 | 06:07:14 INFO - PROCESS | 25056 | [#1] ext.html Cycles:180 Average:67604.70 Median:213889.70 stddev:138641.82 (64.8%) stddev-sans-first:138939.43 06:07:14 INFO - PROCESS | 25056 | Values: 583.5 645.4 664.3 666.5 668.5 45275.8 46646.0 46827.1 51049.0 51133.0 48480.3 48690.3 48725.3 49090.8 49100.0 8468.7 9221.4 9349.5 9365.3 10967.1 10774.9 11210.8 11218.7 11218.8 11231.3 11191.1 11210.0 11220.3 11230.0 11292.5 11078.5 11121.4 11132.4 11303.5 11315.3 11115.9 11187.2 11192.0 11193.7 11205.6 44776.6 45083.3 45204.8 45281.1 45306.0 3561.2 3578.6 3690.8 3739.9 3939.5 34945.7 35362.3 35445.0 35498.0 35601.0 4737.7 4800.1 4807.4 4835.4 4888.8 382769.0 385306.0 386640.1 388045.0 388424.0 62598.4 62982.0 63084.0 63113.7 63291.0 39355.4 40234.2 40389.2 43125.0 43138.0 17004.3 17128.1 17143.6 17173.2 17289.7 11188.9 12694.4 12888.3 12907.9 12917.8 20810.4 20903.9 20990.0 21024.5 21094.4 7373.3 7562.9 7630.8 7685.8 7713.7 1090.3 1115.5 1139.8 1194.9 1202.5 33720.3 36259.3 36365.6 36414.5 36485.1 1176.5 1201.9 1211.1 1215.1 1244.2 18815.8 18903.0 18908.4 19090.2 19109.9 660436.0 666439.0 673486.0 673612.0 679003.0 65626.0 66160.3 66312.7 66358.7 66366.7 1318.8 1434.6 1457.4 1459.4 1490.7 31380.4 32943.5 33012.3 33054.3 33163.2 55640.0 56115.7 56235.7 56363.7 56449.7 462593.0 463066.0 464303.0 464750.0 465174.0 31023.5 31332.2 32207.0 32295.5 33739.0 54306.7 54434.5 55092.7 55264.7 55320.0 45200.0 45708.5 45943.5 46340.3 47037.5 44806.8 45806.3 46178.5 46287.8 47046.3 45350.9 45434.3 46385.3 46786.5 47527.5 37357.2 40058.6 40243.4 40806.4 40985.4 30413.6 30553.4 30752.8 30819.4 30963.4 06:07:14 INFO - PROCESS | 25056 | 06:07:14 INFO - PROCESS | 25056 | [#2] jquery.html Cycles:180 Average:33057.98 Median:11730.39 stddev:97727.80 (833.1%) stddev-sans-first:97971.16 06:07:14 INFO - PROCESS | 25056 | Values: 384.2 401.9 410.5 413.1 422.5 16426.5 20148.4 20319.1 20527.6 21039.2 20717.7 20895.1 21085.7 21248.4 21287.8 5853.5 6027.7 6062.2 6062.9 6096.7 4453.1 5824.5 5870.5 5874.6 6431.2 6616.2 6764.3 6770.8 6771.6 6772.9 5573.7 5764.4 5784.2 5849.6 6769.5 5811.2 5814.9 5869.2 5900.8 5939.8 20724.8 24437.1 24558.3 24879.0 25115.6 2418.7 2478.2 2518.7 2527.2 3138.4 10760.1 10870.8 11502.6 11931.6 13287.0 2027.3 2092.4 2147.0 2151.4 2155.8 83638.2 87090.0 87730.6 89477.0 91429.0 11096.9 11175.4 11434.5 11930.2 11996.2 15441.5 15460.6 15494.7 15529.5 15543.8 7607.2 7747.0 7750.4 7792.1 8156.6 7284.6 7414.5 7507.8 7674.9 7902.7 4629.9 4785.3 4849.7 4945.4 5004.9 2038.4 2051.2 2078.3 2079.0 2123.3 976.2 1008.9 1039.7 1055.8 1089.7 20519.8 20788.2 21230.6 21280.9 21313.8 1040.2 1051.3 1069.9 1088.5 1104.1 11300.5 11641.9 11677.4 11686.3 11839.9 569278.0 577258.0 591022.0 611190.0 618805.0 97988.5 103340.0 103996.0 106814.0 107088.5 1023.8 1032.1 1063.5 1068.4 1079.3 18702.6 18922.8 19096.6 19131.6 19259.6 32542.0 35439.6 45194.5 45194.8 45609.5 62847.0 64295.7 64524.4 65891.0 66571.0 8726.0 8979.4 9082.1 9086.4 9230.9 13180.2 13460.3 13660.4 13728.4 14166.3 12801.7 12846.3 12908.4 13326.5 13469.6 12446.8 12621.2 12734.5 12738.7 12997.9 12422.8 12991.6 13021.9 13357.1 13362.9 9453.4 9519.0 9593.5 9625.3 9636.6 10831.9 11931.9 12664.7 13005.2 13081.9 06:07:14 INFO - PROCESS | 25056 | 06:07:14 INFO - PROCESS | 25056 | [#3] mootools.html Cycles:180 Average:6262.12 Median:4854.92 stddev:3585.90 (73.9%) stddev-sans-first:3577.56 06:07:14 INFO - PROCESS | 25056 | Values: 1429.4 1523.3 1550.7 1742.5 1879.0 8857.1 9262.4 9357.6 9632.4 9640.1 9444.6 9552.4 9587.2 9596.9 9617.2 2485.3 2501.5 2506.7 2508.1 2517.3 2447.6 2504.1 2505.0 2508.7 2517.2 2454.6 2464.3 2474.8 2475.0 2479.8 2391.0 2402.3 2423.8 2465.7 2474.3 2407.7 2426.6 2437.1 2438.3 2449.6 9387.8 9390.4 9472.1 9482.1 9549.4 5633.1 5861.0 5873.9 5877.6 5878.6 3557.5 3623.0 3675.1 3713.2 3724.0 1186.2 1205.5 1210.2 1214.8 1229.6 13393.1 13430.5 13437.9 13451.5 13466.5 4881.6 4916.4 4952.3 5029.9 5123.4 10522.3 10523.4 10527.1 10538.2 10544.2 5703.2 5744.4 5755.9 5761.5 5761.5 4721.5 4735.4 5276.4 5369.9 5398.4 4248.5 4271.3 4271.7 4281.9 4323.6 1284.4 1326.3 1328.5 1333.1 1335.0 1001.9 1055.9 1080.1 1087.2 1087.5 6576.7 6583.5 6635.8 6799.9 6828.1 492.8 496.6 503.4 506.9 507.9 4416.3 4681.3 4703.3 4775.6 4828.3 11704.8 12187.8 12199.9 12223.9 12226.6 11115.3 11534.7 11593.1 11915.9 12073.9 8554.4 8672.6 8716.0 8746.3 8763.3 6544.4 6753.5 6847.8 6908.3 6989.2 10901.8 10951.5 10979.8 11054.6 11160.9 9729.3 10646.7 10964.1 11347.3 11403.0 8047.8 8366.8 8801.3 8806.8 8807.0 9188.0 9432.6 9454.0 9514.0 9532.7 8217.3 8356.0 8376.2 8492.7 8551.0 8126.1 8128.6 8192.5 8267.5 8345.2 6985.0 7153.9 7448.4 7500.1 7512.7 5432.3 5531.6 5572.6 5657.4 5716.8 5437.5 5438.1 5535.1 5561.7 5580.0 06:07:14 INFO - PROCESS | 25056 | 06:07:14 INFO - PROCESS | 25056 | [#4] prototype.html Cycles:180 Average:4670.25 Median:5154.52 stddev:1332.72 (25.9%) stddev-sans-first:1296.50 06:07:14 INFO - PROCESS | 25056 | Values: 354.7 355.6 356.9 357.8 360.1 5533.0 5542.4 5544.4 5557.8 5566.0 5453.6 5469.8 5495.5 5495.9 5496.4 3844.4 4030.2 4274.7 4323.4 4329.8 4281.8 4285.8 4298.9 4306.4 4322.9 4078.1 4268.1 4276.4 4276.7 4291.6 4201.2 4243.6 4285.4 4287.1 4293.9 3814.8 3815.0 3815.6 3823.0 3826.5 5188.5 5292.7 5317.4 5317.9 5321.8 4896.8 4913.9 4929.3 4937.7 4961.8 5603.3 5638.2 5651.4 5663.5 5674.9 4108.9 4191.8 4214.3 4219.6 4224.7 5595.9 5766.4 5901.7 5912.7 5917.9 4288.7 4304.3 4309.5 4317.4 4317.9 4364.3 4367.8 4370.1 4371.8 4371.9 4809.6 4905.0 4927.8 4935.8 4937.1 5682.1 5694.9 5707.4 5726.7 5746.3 1491.7 1511.0 1517.9 1523.7 1524.0 931.8 974.2 977.8 978.9 992.6 4917.3 4926.5 4943.4 4949.9 4985.0 5133.6 5141.4 5167.7 5176.7 5188.9 4635.9 4898.4 4939.5 4959.0 4973.7 2744.1 2751.7 2752.1 2760.7 2768.8 5292.2 5302.4 5306.7 5322.5 5346.0 4971.3 5185.7 5215.6 5222.5 5262.4 5289.8 5298.8 5303.1 5312.3 5318.7 5113.7 5126.6 5139.3 5176.7 5184.9 4854.6 5052.5 5075.0 5080.5 5103.0 5334.9 5859.6 5916.2 6060.9 6095.8 4292.0 4293.8 4294.5 4301.0 4309.3 5706.5 5861.0 5869.6 5872.0 5886.0 5680.7 5702.7 5752.0 5763.7 5803.5 5690.3 5730.8 5745.0 5776.7 5792.3 5417.9 5668.8 5669.0 5717.7 5727.7 5785.6 5805.9 5821.6 5826.2 5828.2 5487.7 5683.0 5704.8 5721.6 5740.3 06:07:14 INFO - PROCESS | 25056 | 06:07:14 INFO - PROCESS | 25056 | [#5] yui.html Cycles:180 Average:22329.18 Median:4164.77 stddev:32185.17 (772.8%) stddev-sans-first:32234.86 06:07:14 INFO - PROCESS | 25056 | Values: 799.9 816.2 831.5 848.2 852.9 15418.2 16051.2 16892.7 16937.2 17086.4 15863.1 15931.0 15963.4 16005.5 16030.7 721.7 842.7 844.8 849.1 849.4 856.9 860.6 862.3 862.4 863.0 916.5 917.9 920.0 920.9 921.2 749.8 890.7 900.6 905.4 910.1 674.6 674.8 674.9 675.1 675.5 15056.9 15095.8 15143.8 15145.6 15147.9 15359.9 15563.5 15741.6 15986.4 16180.5 13645.2 13669.0 14001.4 14017.2 14534.0 4494.8 4628.1 4740.6 4745.1 4783.1 68760.0 69434.7 69721.7 82720.0 84591.0 12843.3 12890.5 12930.2 13109.2 13336.9 22965.5 22982.2 22991.2 22991.2 23067.1 9418.5 9538.0 9572.5 9651.2 9835.9 5098.8 5220.3 5288.0 5332.7 5394.8 4014.3 4158.4 4171.1 4172.4 4177.9 665.2 670.2 678.4 687.1 697.9 742.0 771.5 798.1 822.2 849.5 10435.5 10659.3 10738.1 11166.3 11312.5 716.6 764.5 773.2 812.9 814.4 4908.8 4995.2 5138.1 5157.3 5166.7 95270.0 129317.0 132952.0 139914.3 142217.0 109831.0 112668.4 114838.0 115422.0 116590.0 75401.0 80242.0 87755.0 89442.0 89796.6 10880.9 10915.6 10940.7 11432.3 11439.8 52003.2 52172.2 52598.5 53201.0 53325.0 74858.0 75618.6 77555.6 78444.6 79413.0 13846.6 14377.9 14404.2 14507.0 14549.0 15876.2 16091.2 16416.8 16817.7 17261.2 14430.9 14511.9 15107.3 17188.8 17201.4 14207.7 14918.6 15146.2 15519.8 15679.2 13252.9 13493.9 15166.4 15315.2 15418.8 10927.8 12208.3 12323.3 12366.0 12823.2 12654.5 12852.9 13193.9 13304.7 13364.3 06:07:14 INFO - PROCESS | 25056 | -------- Summary: end -------- 06:07:14 INFO - PROCESS | 25056 | 06:07:14 INFO - TEST-INFO | 25056: exit 0 06:07:14 INFO - TEST-OK | dromaeo_css | took 1114250ms 06:07:14 INFO - TEST-START | kraken 06:07:14 INFO - operating with platform_type : linux_ 06:07:14 INFO - Initialising browser for kraken test... 06:07:14 INFO - TEST-INFO | started process 25260 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm48qM7/profile http://localhost:52168/getInfo.html) 06:07:18 INFO - PROCESS | 25260 | __metrics Screen width/height:1600/1200 06:07:18 INFO - PROCESS | 25260 | colorDepth:24 06:07:18 INFO - PROCESS | 25260 | Browser inner width/height: 1024/697 06:07:18 INFO - PROCESS | 25260 | __metrics 06:07:19 INFO - PROCESS | 25260 | 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" 06:07:19 INFO - PROCESS | 25260 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:07:20 INFO - TEST-INFO | 25260: exit 0 06:07:20 INFO - Browser initialized. 06:07:20 INFO - Running cycle 1/1 for kraken test... 06:07:20 INFO - TEST-INFO | started process 25347 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm48qM7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:07:32 INFO - PROCESS | 25347 | RSS: Main: 146251776 06:07:32 INFO - PROCESS | 25347 | 06:07:35 INFO - PROCESS | 25347 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52168/tests/kraken/driver.html?testName=audio-beat-detection) 06:07:36 INFO - PROCESS | 25347 | RSS: Main: 200601600 06:07:36 INFO - PROCESS | 25347 | 06:07:38 INFO - PROCESS | 25347 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52168/tests/kraken/driver.html?testName=audio-dft) 06:07:39 INFO - PROCESS | 25347 | RSS: Main: 185745408 06:07:39 INFO - PROCESS | 25347 | 06:07:41 INFO - PROCESS | 25347 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52168/tests/kraken/driver.html?testName=audio-fft) 06:07:42 INFO - PROCESS | 25347 | RSS: Main: 276709376 06:07:42 INFO - PROCESS | 25347 | 06:07:44 INFO - PROCESS | 25347 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52168/tests/kraken/driver.html?testName=audio-oscillator) 06:07:45 INFO - PROCESS | 25347 | RSS: Main: 182136832 06:07:45 INFO - PROCESS | 25347 | 06:07:47 INFO - PROCESS | 25347 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52168/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:07:47 INFO - PROCESS | 25347 | RSS: Main: 180363264 06:07:47 INFO - PROCESS | 25347 | 06:07:50 INFO - PROCESS | 25347 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52168/tests/kraken/driver.html?testName=imaging-darkroom) 06:07:51 INFO - PROCESS | 25347 | RSS: Main: 216530944 06:07:51 INFO - PROCESS | 25347 | 06:07:54 INFO - PROCESS | 25347 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52168/tests/kraken/driver.html?testName=imaging-desaturate) 06:07:55 INFO - PROCESS | 25347 | RSS: Main: 215511040 06:07:55 INFO - PROCESS | 25347 | 06:07:58 INFO - PROCESS | 25347 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52168/tests/kraken/driver.html?testName=json-parse-financial) 06:07:58 INFO - PROCESS | 25347 | RSS: Main: 215576576 06:07:58 INFO - PROCESS | 25347 | 06:08:00 INFO - PROCESS | 25347 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52168/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:08:01 INFO - PROCESS | 25347 | RSS: Main: 177721344 06:08:01 INFO - PROCESS | 25347 | 06:08:02 INFO - PROCESS | 25347 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52168/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:08:03 INFO - PROCESS | 25347 | RSS: Main: 185761792 06:08:03 INFO - PROCESS | 25347 | 06:08:05 INFO - PROCESS | 25347 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52168/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:08:06 INFO - PROCESS | 25347 | RSS: Main: 186441728 06:08:06 INFO - PROCESS | 25347 | 06:08:08 INFO - PROCESS | 25347 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52168/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:08:09 INFO - PROCESS | 25347 | RSS: Main: 182525952 06:08:09 INFO - PROCESS | 25347 | 06:08:11 INFO - PROCESS | 25347 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52168/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:08:12 INFO - PROCESS | 25347 | RSS: Main: 184410112 06:08:12 INFO - PROCESS | 25347 | 06:08:13 INFO - PROCESS | 25347 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52168/tests/kraken/driver.html?testName=ai-astar) 06:08:14 INFO - PROCESS | 25347 | RSS: Main: 235331584 06:08:14 INFO - PROCESS | 25347 | 06:08:14 INFO - PROCESS | 25347 | __start_tp_report 06:08:14 INFO - PROCESS | 25347 | _x_x_mozilla_page_load 06:08:14 INFO - PROCESS | 25347 | _x_x_mozilla_page_load_details 06:08:14 INFO - PROCESS | 25347 | |i|pagename|runs| 06:08:14 INFO - PROCESS | 25347 | |0;ai-astar;112;113;120;110;111;112;110;111;112;111 06:08:14 INFO - PROCESS | 25347 | |1;audio-beat-detection;139;118;140;160;117;148;144;115;168;119 06:08:14 INFO - PROCESS | 25347 | |2;audio-dft;160;161;161;161;160;161;160;160;159;161 06:08:14 INFO - PROCESS | 25347 | |3;audio-fft;76;75;75;173;75;76;75;74;168;75 06:08:14 INFO - PROCESS | 25347 | |4;audio-oscillator;83;83;158;83;82;84;150;83;83;152 06:08:14 INFO - PROCESS | 25347 | |5;imaging-gaussian-blur;116;117;117;117;117;118;119;118;119;119 06:08:14 INFO - PROCESS | 25347 | |6;imaging-darkroom;146;147;147;147;147;146;146;147;146;146 06:08:14 INFO - PROCESS | 25347 | |7;imaging-desaturate;95;96;95;95;95;95;96;95;96;95 06:08:14 INFO - PROCESS | 25347 | |8;json-parse-financial;49;49;50;51;51;50;50;50;50;51 06:08:14 INFO - PROCESS | 25347 | |9;json-stringify-tinderbox;62;60;60;59;60;59;58;60;60;60 06:08:14 INFO - PROCESS | 25347 | |10;stanford-crypto-aes;72;75;73;77;76;72;72;77;72;74 06:08:14 INFO - PROCESS | 25347 | |11;stanford-crypto-ccm;126;131;123;119;124;122;118;127;148;119 06:08:14 INFO - PROCESS | 25347 | |12;stanford-crypto-pbkdf2;153;151;153;152;154;150;159;153;152;151 06:08:14 INFO - PROCESS | 25347 | |13;stanford-crypto-sha256-iterative;65;62;63;61;62;62;62;60;62;60 06:08:14 INFO - PROCESS | 25347 | __end_tp_report 06:08:14 INFO - PROCESS | 25347 | __start_cc_report 06:08:14 INFO - PROCESS | 25347 | _x_x_mozilla_cycle_collect,1658 06:08:14 INFO - PROCESS | 25347 | __end_cc_report 06:08:14 INFO - PROCESS | 25347 | __startTimestamp1473340094217__endTimestamp 06:08:14 INFO - PROCESS | 25347 | 06:08:14 INFO - PROCESS | 25347 | ------- Summary: start ------- 06:08:14 INFO - PROCESS | 25347 | Number of tests: 14 06:08:14 INFO - PROCESS | 25347 | 06:08:14 INFO - PROCESS | 25347 | [#0] ai-astar Cycles:10 Average:112.20 Median:112.00 stddev:2.90 (2.6%) stddev-sans-first:3.07 06:08:14 INFO - PROCESS | 25347 | Values: 112.0 113.0 120.0 110.0 111.0 112.0 110.0 111.0 112.0 111.0 06:08:14 INFO - PROCESS | 25347 | 06:08:14 INFO - PROCESS | 25347 | [#1] audio-beat-detection Cycles:10 Average:136.80 Median:142.00 stddev:18.98 (13.4%) stddev-sans-first:20.11 06:08:14 INFO - PROCESS | 25347 | Values: 139.0 118.0 140.0 160.0 117.0 148.0 144.0 115.0 168.0 119.0 06:08:14 INFO - PROCESS | 25347 | 06:08:14 INFO - PROCESS | 25347 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:0.70 (0.4%) stddev-sans-first:0.73 06:08:14 INFO - PROCESS | 25347 | Values: 160.0 161.0 161.0 161.0 160.0 161.0 160.0 160.0 159.0 161.0 06:08:14 INFO - PROCESS | 25347 | 06:08:14 INFO - PROCESS | 25347 | [#3] audio-fft Cycles:10 Average:94.20 Median:75.00 stddev:40.23 (53.6%) stddev-sans-first:42.13 06:08:14 INFO - PROCESS | 25347 | Values: 76.0 75.0 75.0 173.0 75.0 76.0 75.0 74.0 168.0 75.0 06:08:14 INFO - PROCESS | 25347 | 06:08:14 INFO - PROCESS | 25347 | [#4] audio-oscillator Cycles:10 Average:104.10 Median:83.00 stddev:34.03 (41.0%) stddev-sans-first:35.23 06:08:14 INFO - PROCESS | 25347 | Values: 83.0 83.0 158.0 83.0 82.0 84.0 150.0 83.0 83.0 152.0 06:08:14 INFO - PROCESS | 25347 | 06:08:14 INFO - PROCESS | 25347 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:1.06 (0.9%) stddev-sans-first:0.93 06:08:14 INFO - PROCESS | 25347 | Values: 116.0 117.0 117.0 117.0 117.0 118.0 119.0 118.0 119.0 119.0 06:08:14 INFO - PROCESS | 25347 | 06:08:14 INFO - PROCESS | 25347 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 06:08:14 INFO - PROCESS | 25347 | Values: 146.0 147.0 147.0 147.0 147.0 146.0 146.0 147.0 146.0 146.0 06:08:14 INFO - PROCESS | 25347 | 06:08:14 INFO - PROCESS | 25347 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 06:08:14 INFO - PROCESS | 25347 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 95.0 06:08:14 INFO - PROCESS | 25347 | 06:08:14 INFO - PROCESS | 25347 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:0.74 (1.5%) stddev-sans-first:0.67 06:08:14 INFO - PROCESS | 25347 | Values: 49.0 49.0 50.0 51.0 51.0 50.0 50.0 50.0 50.0 51.0 06:08:14 INFO - PROCESS | 25347 | 06:08:14 INFO - PROCESS | 25347 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:1.03 (1.7%) stddev-sans-first:0.73 06:08:14 INFO - PROCESS | 25347 | Values: 62.0 60.0 60.0 59.0 60.0 59.0 58.0 60.0 60.0 60.0 06:08:14 INFO - PROCESS | 25347 | 06:08:14 INFO - PROCESS | 25347 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:74.50 stddev:2.11 (2.8%) stddev-sans-first:2.11 06:08:14 INFO - PROCESS | 25347 | Values: 72.0 75.0 73.0 77.0 76.0 72.0 72.0 77.0 72.0 74.0 06:08:14 INFO - PROCESS | 25347 | 06:08:14 INFO - PROCESS | 25347 | [#11] stanford-crypto-ccm Cycles:10 Average:125.70 Median:125.00 stddev:8.82 (7.1%) stddev-sans-first:9.35 06:08:14 INFO - PROCESS | 25347 | Values: 126.0 131.0 123.0 119.0 124.0 122.0 118.0 127.0 148.0 119.0 06:08:14 INFO - PROCESS | 25347 | 06:08:14 INFO - PROCESS | 25347 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.80 Median:153.00 stddev:2.49 (1.6%) stddev-sans-first:2.64 06:08:14 INFO - PROCESS | 25347 | Values: 153.0 151.0 153.0 152.0 154.0 150.0 159.0 153.0 152.0 151.0 06:08:14 INFO - PROCESS | 25347 | 06:08:14 INFO - PROCESS | 25347 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:1.45 (2.3%) stddev-sans-first:1.01 06:08:14 INFO - PROCESS | 25347 | Values: 65.0 62.0 63.0 61.0 62.0 62.0 62.0 60.0 62.0 60.0 06:08:14 INFO - PROCESS | 25347 | -------- Summary: end -------- 06:08:14 INFO - PROCESS | 25347 | 06:08:15 INFO - TEST-INFO | 25347: exit 0 06:08:15 INFO - TEST-OK | kraken | took 60620ms 06:08:15 INFO - SUITE-END | took 1174s 06:08:15 INFO - Completed test suite (00:19:34) 06:08:15 INFO - javascript benchmark 06:08:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3238.158078899486, 3269.280758233188, 3269.5422640830275, 3283.8522266498007, 3323.135984643992, 5637.379888212294, 5752.165935939292, 5757.1652110444065, 5773.682447465388, 5796.362400135987, 5705.0, 5776.624519406236, 5776.711164441772, 5784.103463963082, 5801.564882633798, 5021.874453138665, 5091.592672586203, 5166.844994650167, 5226.05930932432, 5247.422783493813, 5138.254953031808, 5188.740562971855, 5191.714455704935, 5191.844244672647, 5195.6622819516615, 5098.439171691111, 5134.358205224347, 5135.8202462913805, 5167.250748641445, 5184.403793563751, 5253.7110458924935, 5258.579313654898, 5267.130923397634, 5271.314729085233, 5283.762230699628, 5018.698878067327, 5080.1363768159135, 5118.846434606968, 5161.4322424533275, 5176.378834539879, 5561.332640083216, 5588.050031494656, 5589.133684278943, 5592.7762889484375, 5603.327600687903, 6107.4503294703245, 6120.663363515005, 6121.816345509642, 6122.8775424491705, 6147.846303842395, 5899.380565040695, 6038.788642397539, 6053.616610909228, 6110.480609148228, 6172.722227499773, 5670.177824215507, 5710.343310519302, 5737.053386191274, 5748.626339287933, 5751.511121554715, 6247.468965137923, 6251.906221406683, 6252.030935205051, 6261.279952805486, 6270.0, 5797.536197104221, 5927.4665280125, 5953.107033944856, 5954.732037058343, 6067.54493412987, 6123.020316749299, 6125.234345706786, 6132.78385080153, 6139.2325959255095, 6144.446999770043, 6032.125341825411, 6088.4215999479975, 6088.512918966472, 6099.146119543268, 6127.142200091991, 5929.436703513245, 5974.910376344358, 6013.248343957005, 6014.218151640259, 6102.115193297036, 4608.101420223092, 4654.418197725285, 4654.953450465519, 4693.577578017995, 4697.966447381571, 4132.91734165321, 4155.667546596265, 4163.3130533461635, 4164.562720914321, 4188.0, 5657.57568182388, 5796.768129274866, 5851.093080572518, 5865.501432378211, 5867.882642347129, 6173.536984726074, 6211.81364559064, 6212.875742485125, 6215.06773983897, 6218.409251121137, 5812.964243603229, 5822.854428639318, 5835.941640583625, 5863.413658634103, 5907.3797251288, 4958.8760280993265, 5052.545270925634, 5084.8220312288895, 5122.692638441706, 5166.173412254021, 6367.267764207083, 6367.777127800505, 6368.044793281045, 6372.617642941438, 6386.521010924199, 6117.388261174025, 6155.384461553809, 6175.166352542349, 6184.319724830355, 6185.659788711664, 6141.8464538385115, 6268.279147897868, 6288.842778930563, 6334.746610135543, 6391.265004524539, 6096.786612468542, 6113.57204995646, 6114.996965385453, 6116.061877111602, 6120.812855101138, 6151.056559034736, 6198.783042593487, 6200.937990620036, 6211.689415529115, 6219.813405597839, 5780.884382312414, 5975.850603734942, 5999.4900433463645, 6038.486728628116, 6086.391360863879, 6050.364711705208, 6051.394860513913, 6053.545984051302, 6059.848503787441, 6085.6348619083, 5946.108083787293, 5954.315253745702, 5984.58107932458, 5994.3705910878725, 6017.638941663514, 5789.507891829073, 5911.793087241926, 5951.047832346804, 5993.67034813072, 6082.9695851520455, 5864.501517371071, 5871.383504732113, 5872.265966754156, 5879.029960056543, 5898.20374249488], "value": 5629.392075149095, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.5098517245513, 645.4126744662357, 664.3423011218891, 666.450178602653, 668.4585485756537, 45275.77362113189, 46646.0, 46827.063458730845, 51049.0, 51133.0, 48480.27279590809, 48690.26964595534, 48725.26912096321, 49090.75454622722, 49100.0, 8468.66125354988, 9221.446713197212, 9349.485778282191, 9365.250779937589, 10967.122630189566, 10774.892251077466, 11210.831837522444, 11218.66344009681, 11218.831717524203, 11231.32612043276, 11191.104711623091, 11210.0, 11220.326780393203, 11230.045446137088, 11292.548298068068, 11078.501467433944, 11121.388323642197, 11132.443377831116, 11303.547858085667, 11315.321080735182, 11115.944420277847, 11187.216894817366, 11192.0, 11193.720156996058, 11205.551777928873, 44776.55223447757, 45083.323750143776, 45204.77397613024, 45281.09437811258, 45306.0, 3561.198730285691, 3578.58846232684, 3690.815459227028, 3739.942386013692, 3939.4878665773267, 34945.65054349449, 35362.29275414503, 35445.0, 35498.0, 35601.0, 4737.73942433166, 4800.135975524404, 4807.423109226916, 4835.44392394872, 4888.755562221874, 382769.0, 385306.0, 386640.066799667, 388045.0, 388424.0, 62598.37401626017, 62982.0, 63084.0, 63113.684431577545, 63291.0, 39355.40966885499, 40234.195316093515, 40389.19221615551, 43125.0, 43138.0, 17004.319827206775, 17128.14359282031, 17143.571410714583, 17173.227204775816, 17289.65420691579, 11188.944055279671, 12694.42875070624, 12888.291143987171, 12907.935460322638, 12917.806232906514, 20810.375688729364, 20903.8954805225, 20990.0, 21024.474388140417, 21094.367168984954, 7373.262673732691, 7562.882392929989, 7630.847383052308, 7685.770276755692, 7713.730019449293, 1090.3022065877844, 1115.5091759625739, 1139.760650263454, 1194.8805119488156, 1202.5129822421934, 33720.32559348799, 36259.274814503566, 36365.63634363622, 36414.453783193276, 36485.08787280286, 1176.5352685689104, 1201.897838683704, 1211.0916812390708, 1215.0826126274605, 1244.234795600699, 18815.81184188168, 18903.0, 18908.44912427656, 19090.2363905445, 19109.904450477938, 660436.0, 666439.0, 673486.0, 673612.0, 679003.0, 65626.0, 66160.33839661638, 66312.6684366575, 66358.66820665865, 66366.66816665983, 1318.756030134428, 1434.619825746178, 1457.380613239369, 1459.408939379553, 1490.7316682997164, 31380.372392551566, 32943.50584741279, 33012.339753205284, 33054.33891322112, 33163.17092072718, 55640.0, 56115.71942140263, 56235.71882140563, 56363.71818140883, 56449.71775141098, 462593.0, 463066.0, 464303.0, 464750.0, 465174.0, 31023.53464697986, 31332.216694582818, 32207.0, 32295.51556726604, 33739.0, 54306.72846635742, 54434.45565544452, 55092.724536378664, 55264.72367638136, 55320.0, 45200.0, 45708.542914570426, 45943.540564593925, 46340.30489542592, 47037.52962470332, 44806.77596611996, 45806.31290530712, 46178.53821461742, 46287.81481297421, 47046.294305586125, 45350.8754456906, 45434.31848522209, 46385.30422043739, 46786.532134678215, 47527.52472475369, 37357.15811063774, 40058.59941400549, 40243.3963490542, 40806.38790418087, 40985.38521922114, 30413.594901911783, 30553.388932220787, 30752.846235768677, 30819.383612328078, 30963.380732384776], "value": 22072.01766786032, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.20469627870284, 401.8774273846478, 410.46229439434336, 413.1159319057218, 422.48034917052024, 16426.507204783844, 20148.39554813358, 20319.085641146146, 20527.589448211027, 21039.158433662637, 20717.68923466149, 20895.119439250466, 21085.68371474429, 21248.36254912351, 21287.787122128775, 5853.531717462603, 6027.668478233717, 6062.181605483247, 6062.878742425149, 6096.695165241742, 4453.064856380164, 5824.475797178253, 5870.530357571405, 5874.647521148723, 6431.196100487439, 6616.20605527335, 6764.3235676432205, 6770.8307292317595, 6771.5937043777285, 6772.932270677279, 5573.749181286831, 5764.365919748845, 5784.161296611967, 5849.590528662995, 6769.458443324522, 5811.215485909433, 5814.941850581482, 5869.207656966298, 5900.76396944126, 5939.821805345847, 20724.792752072437, 24437.144699935594, 24558.263252102464, 24879.0, 25115.62326565103, 2418.661387405765, 2478.157426475007, 2518.735532769051, 2527.166035208377, 3138.419392412411, 10760.139188864872, 10870.782584348268, 11502.597409090726, 11931.582394616147, 13287.0, 2027.2701827342146, 2092.4141240452695, 2147.0, 2151.3653472225574, 2155.8490905636613, 83638.16361836425, 87090.0, 87730.56134719285, 89477.0, 91429.0, 11096.944515277533, 11175.38535380562, 11434.485448154854, 11930.22453540517, 11996.220245684002, 15441.459548915958, 15460.613484662974, 15494.690106198039, 15529.534113976599, 15543.84456155424, 7607.239276072459, 7747.0, 7750.412467449863, 7792.064952205772, 8156.632951517196, 7284.599347035858, 7414.518056326321, 7507.812304692427, 7674.923250767532, 7902.723404680809, 4629.907401852011, 4785.3300537924715, 4849.733264670477, 4945.406551213878, 5004.874878128076, 2038.3579172560596, 2051.2307884543297, 2078.303768237657, 2078.958420831575, 2123.2886982860914, 976.1653786012856, 1008.8890222075565, 1039.6880935719255, 1055.7888422315568, 1089.7057794395469, 20519.794802051787, 20788.1684732612, 21230.575388492143, 21280.893595531925, 21313.786862131492, 1040.1730624153815, 1051.3271506236015, 1069.9251052426255, 1088.5319312695458, 1104.0560320925654, 11300.491477883515, 11641.941790290994, 11677.357745324089, 11686.298822070703, 11839.88160118405, 569278.0, 577258.0, 591022.0, 611190.0, 618805.0, 97988.51005745068, 103340.0, 103996.0, 106814.0, 107088.4645576767, 1023.81059503992, 1032.0917592518535, 1063.4948399510326, 1068.4444089073793, 1079.3416016230094, 18702.6259474807, 18922.810771892106, 19096.618067638847, 19131.617367652292, 19259.61480770349, 32542.0, 35439.64560354467, 45194.54805451904, 45194.77402612966, 45609.54390456053, 62847.0, 64295.67852160709, 64524.35475645184, 65891.0, 66571.0, 8725.956370218108, 8979.371443999118, 9082.137196966278, 9086.36395452312, 9230.907690923004, 13180.209187448783, 13460.326983650582, 13660.385282662106, 13728.443668938327, 14166.291685415481, 12801.743965120831, 12846.293453860128, 12908.41912113976, 13326.533571324957, 13469.595912122651, 12446.751064978469, 12621.242725436136, 12734.490620375082, 12738.68153296175, 12997.935010324887, 12422.813657794959, 12991.610251692464, 13021.934890325489, 13357.090807454548, 13362.866371336551, 9453.385529940531, 9519.0, 9593.472359020321, 9625.32622716403, 9636.566354514203, 10831.945840270748, 11931.940340298243, 12664.6833829155, 13005.154664946747, 13081.869181308066], "value": 9832.3947682366, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1429.363933049792, 1523.268830961139, 1550.6898620275945, 1742.520806778136, 1878.9812101878977, 8857.114288571147, 9262.351635385527, 9357.625694972201, 9632.422054676725, 9640.084192001748, 9444.622215111405, 9552.426854388741, 9587.185089267403, 9596.904030959688, 9617.230621550276, 2485.291691867818, 2501.462185630089, 2506.6741323627903, 2508.147229941819, 2517.25740906433, 2447.596146635803, 2504.098524531167, 2505.0355613089, 2508.7114981777054, 2517.2322441655288, 2454.582720937436, 2464.2607217834666, 2474.7525247475287, 2475.0347364527847, 2479.838810477313, 2390.988045059781, 2402.327348342467, 2423.8303318767694, 2465.6917885264343, 2474.3195621204313, 2407.723111842143, 2426.551088048717, 2437.061731233488, 2438.2563318187945, 2449.5835707929696, 9387.812243755156, 9390.436573805593, 9472.147506724428, 9482.146606805352, 9549.379290346105, 5633.070543360341, 5861.0, 5873.85315367115, 5877.588568800185, 5878.61788983719, 3557.51973483579, 3623.0398944279787, 3675.1363429594026, 3713.1830997180846, 3723.9813800930824, 1186.1993154620682, 1205.4515195585986, 1210.1589395370222, 1214.8117041858527, 1229.643403413009, 13393.06248562601, 13430.529931452353, 13437.932810335984, 13451.461941522328, 13466.528671496451, 4881.585065269421, 4916.410030796327, 4952.257161425743, 5029.949700502948, 5123.436421993578, 10522.263441559018, 10523.368597884151, 10527.105196058268, 10538.156947444186, 10544.209184311054, 5703.230063941398, 5744.396838331999, 5755.942440575541, 5761.510271626875, 5761.53907687384, 4721.504242054603, 4735.431748190239, 5276.3932147802725, 5369.919451208235, 5398.379186393537, 4248.532661407243, 4271.31658934569, 4271.743695378287, 4281.914361712748, 4323.610875021201, 1284.4284293489306, 1326.257295914291, 1328.4885319152197, 1333.113479536117, 1335.038772084098, 1001.8897921228659, 1055.8574592429923, 1080.0657431322006, 1087.1683162380784, 1087.505185140759, 6576.671166441659, 6583.473322134218, 6635.800925972132, 6799.932000680029, 6828.146481689789, 492.7979528393413, 496.6225668491972, 503.3657591434745, 506.9138246498104, 507.9212722028092, 4416.271315232951, 4681.274402467623, 4703.294505824086, 4775.617950563947, 4828.251620998681, 11704.824427633592, 12187.817182742267, 12199.878001219875, 12223.877761222451, 12226.633201003984, 11115.333080015063, 11534.653960381202, 11593.072554195644, 11915.88084119165, 12073.879261207276, 8554.44396114263, 8672.56637168139, 8715.956420217732, 8746.256568191648, 8763.342749293828, 6544.411003009752, 6753.493487988322, 6847.828804279933, 6908.3091690830515, 6989.231184569591, 10901.83647245276, 10951.45242737876, 10979.83530247031, 11054.557817687202, 11160.944195279297, 9729.270304727032, 10646.73383165427, 10964.122870170208, 11347.262427942158, 11403.0, 8047.758567242992, 8366.832663346577, 8801.295896328407, 8806.779830504289, 8806.955965220133, 9188.0, 9432.575534101119, 9454.0, 9514.048595140708, 9532.714018579454, 8217.30152937007, 8355.95822020886, 8376.246137847624, 8492.74521764348, 8551.0, 8126.065502467292, 8128.593570321342, 8192.54940978252, 8267.503949763033, 8345.248927596303, 6984.965075174795, 7153.928460715326, 7448.366888814511, 7500.099988001475, 7512.661930213044, 5432.320959880015, 5531.640443371168, 5572.582056345709, 5657.43425657431, 5716.79991200306, 5437.510624043855, 5438.075527160313, 5535.11438169907, 5561.6663000220115, 5580.023495888122], "value": 4915.28685900616, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.6914184659347, 355.61593479042637, 356.9482425048368, 357.75315032627486, 360.0781998084903, 5532.972335138324, 5542.362628297737, 5544.362398324194, 5557.8054768083075, 5565.972170139144, 5453.64551304166, 5469.753861076261, 5495.477929596692, 5495.862603434916, 5496.395396506381, 3844.384898416254, 4030.214108248885, 4274.743515389071, 4323.351497275403, 4329.766016685246, 4281.807318670667, 4285.828566857322, 4298.892527686801, 4306.35404689296, 4322.935155972663, 4078.143589846136, 4268.146370725852, 4276.401303817468, 4276.6792490563175, 4291.592298731615, 4201.159768046403, 4243.618074373306, 4285.3786201000685, 4287.142571485684, 4293.874532106596, 3814.771113733185, 3814.9809250953567, 3815.5993620669983, 3822.9808850955847, 3826.5408151022057, 5188.455212202702, 5292.68243905363, 5317.388500322474, 5317.8936421271355, 5321.813736519242, 4896.804127834882, 4913.877153071202, 4929.260610908356, 4937.728424936627, 4961.801527938878, 5603.299587551556, 5638.154276858463, 5651.4348565143155, 5663.461971112738, 5674.914876276859, 4108.897277568055, 4191.811368488426, 4214.304639734441, 4219.599138081878, 4224.704270701052, 5595.916061259084, 5766.394528574481, 5901.734421951022, 5912.674802885839, 5917.940820591782, 4288.678349123828, 4304.332828411601, 4309.525952145262, 4317.352397140454, 4317.892052698708, 4364.345348197732, 4367.837920229444, 4370.125974805052, 4371.846985355561, 4371.912561748747, 4809.639277054235, 4905.043516514245, 4927.7536123193695, 4935.790731270789, 4937.136001199776, 5682.147677848273, 5694.9430505695245, 5707.400722924116, 5726.713664316768, 5746.252987111732, 1491.701659668049, 1511.0, 1517.8937474376908, 1523.7333466643292, 1523.9695206096037, 931.8165929269925, 974.1573538888811, 977.8093271811929, 978.9118979291898, 992.573193526776, 4917.311576379309, 4926.482719314492, 4943.442815513102, 4949.876253093701, 4984.956167971114, 5133.640645154804, 5141.377897272418, 5167.664101833444, 5176.7411629418375, 5188.948110518922, 4635.884102897455, 4898.363212782316, 4939.46876468296, 4958.9504104959215, 4973.7015779052645, 2744.051723276456, 2751.6697996240177, 2752.0918097027934, 2760.6687197536426, 2768.820026698258, 5292.232626269247, 5302.363716353985, 5306.673331667083, 5322.520973112439, 5345.973270133624, 4971.254311853251, 5185.714785686823, 5215.556677682363, 5222.503862133091, 5262.421133675292, 5289.841304760786, 5298.761555730079, 5303.0719624066405, 5312.282841816305, 5318.73406329682, 5113.718745469035, 5126.641135120506, 5139.254808052886, 5176.741162941913, 5184.870378240575, 4854.611631069435, 5052.520010598987, 5074.974625126851, 5080.517350851663, 5102.974485127551, 5334.919976200283, 5859.589828711949, 5916.171735957056, 6060.909086363797, 6095.817125486243, 4292.0, 4293.763842988666, 4294.506131794758, 4300.978495107504, 4309.288967320357, 5706.457886500776, 5860.970695146668, 5869.589128760946, 5872.0, 5886.0, 5680.6591604503865, 5702.749432823417, 5752.0, 5763.682997435014, 5803.47768700819, 5690.321355200309, 5730.799422020376, 5744.971275143597, 5776.740046697824, 5792.304923409218, 5417.945820541744, 5668.773249069991, 5668.959174697188, 5717.7141142941855, 5727.6849773262875, 5785.623934444247, 5805.854853628524, 5821.592488525763, 5826.213461182856, 5828.213191219131, 5487.670739755627, 5683.0, 5704.80033198836, 5721.628094173865, 5740.282464691913], "value": 4254.829493525396, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.9280064794167, 816.1756625807933, 831.5343407691693, 848.1518481518482, 852.8635418333066, 15418.152001639904, 16051.197440127991, 16892.662146757073, 16937.237824297907, 17086.401975930847, 15863.127527985956, 15931.0, 15963.361465541364, 16005.519834404957, 16030.679386412265, 721.6932803558493, 842.7387509871928, 844.7592436155682, 849.087231226431, 849.3587341557115, 856.9143085691443, 860.5525126933991, 862.3015357560365, 862.4221771413137, 863.0075413274722, 916.4821875640275, 917.8531434970406, 919.9816003679922, 920.8987011428737, 921.2491819167391, 749.8425330680564, 890.6793554320471, 900.6307413960278, 905.42053086025, 910.0671811393308, 674.6289540752615, 674.8312921769558, 674.9392554670103, 675.1021141881287, 675.5271310082912, 15056.924715376463, 15095.849041509555, 15143.848561514355, 15145.621359466102, 15147.924260378737, 15359.923200384039, 15563.455279065178, 15741.606459838596, 15986.44047458345, 16180.514584562481, 13645.249511276876, 13669.0, 14001.369938352798, 14017.158970461805, 14534.0, 4494.8202071917085, 4628.143793398232, 4740.644451666139, 4745.145873742725, 4783.139034973703, 68760.0, 69434.65282673555, 69721.65139174322, 82720.0, 84591.0, 12843.293618850867, 12890.548830790878, 12930.224186548838, 13109.213447193199, 13336.866631333562, 22965.54068918646, 22982.195623153108, 22991.195308164133, 22991.195308164133, 23067.07731690747, 9418.481983490974, 9537.952310238543, 9572.521373931275, 9651.227901767841, 9835.901640983499, 5098.847034588968, 5220.3077014670735, 5287.9735601321745, 5332.68003919766, 5394.78420863169, 4014.297497937849, 4158.397032430341, 4171.144915292358, 4172.374143878443, 4177.853775117917, 665.2250128600224, 670.189071223815, 678.4402867634221, 687.1067612104245, 697.9267176946449, 742.0057123454596, 771.4676872957641, 798.1021350980147, 822.1860358245294, 849.5412477262332, 10435.478226088666, 10659.307145035547, 10738.087262582612, 11166.274192177483, 11312.547498100148, 716.5951237550767, 764.4686942574968, 773.2460850670551, 812.8780682897612, 814.4258297899967, 4908.754562271872, 4995.150824359867, 5138.126518491866, 5157.252198431282, 5166.741662916839, 95270.0472995275, 129317.0, 132952.0, 139914.30042849923, 142217.0, 109831.0, 112668.43665781783, 114838.0, 115422.0, 116590.0, 75401.0, 80242.0, 87755.0, 89442.03090551833, 89796.5510172458, 10880.945595271973, 10915.563377464812, 10940.671779846618, 11432.314061156358, 11439.771204576029, 52003.21995170151, 52172.217416739535, 52598.47401525936, 53201.0, 53325.0, 74858.0, 75618.62190689011, 77555.61222193853, 78444.60777696075, 79413.0, 13846.584602461942, 14377.928110359382, 14404.20776857241, 14507.0, 14548.981571289909, 15876.206189690238, 16091.195440228175, 16416.83583164201, 16817.747733784247, 17261.223244953755, 14430.927845361126, 14511.927440363153, 15107.32017059213, 17188.82811171872, 17201.397951071653, 14207.715845682822, 14918.627034324229, 15146.189487138485, 15519.844801552292, 15679.216039198223, 13252.933735331262, 13493.865061349654, 15166.393344266548, 15315.234238287818, 15418.768718469008, 10927.83608245861, 12208.267503949792, 12323.322217278135, 12366.0, 12823.230606163661, 12654.493820247455, 12852.935735321264, 13193.93403033017, 13304.733905322033, 13364.264965426606], "value": 8260.177074820956, "unit": "score"}], "name": "dromaeo_css", "value": 7716.225637075455, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 120.0, 110.0, 111.0, 112.0, 110.0, 111.0, 112.0, 111.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 118.0, 140.0, 160.0, 117.0, 148.0, 144.0, 115.0, 168.0, 119.0], "value": 136.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 161.0, 160.0, 161.0, 160.0, 160.0, 159.0, 161.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 173.0, 75.0, 76.0, 75.0, 74.0, 168.0, 75.0], "value": 94.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 158.0, 83.0, 82.0, 84.0, 150.0, 83.0, 83.0, 152.0], "value": 104.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 118.0, 119.0, 118.0, 119.0, 119.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 147.0, 147.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 50.0, 51.0, 51.0, 50.0, 50.0, 50.0, 50.0, 51.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 60.0, 59.0, 60.0, 59.0, 58.0, 60.0, 60.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 73.0, 77.0, 76.0, 72.0, 72.0, 77.0, 72.0, 74.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 131.0, 123.0, 119.0, 124.0, 122.0, 118.0, 127.0, 148.0, 119.0], "value": 125.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 151.0, 153.0, 152.0, 154.0, 150.0, 159.0, 153.0, 152.0, 151.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 63.0, 61.0, 62.0, 62.0, 62.0, 60.0, 62.0, 60.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1491.5, "alertThreshold": 2.0}]} 06:08:15 INFO - Return code: 0 06:08:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:08:15 INFO - # TBPL SUCCESS # 06:08:15 INFO - Running post-action listener: _resource_record_post_action 06:08:15 INFO - [mozharness: 2016-09-08 13:08:15.505885Z] Finished run-tests step (success) 06:08:15 INFO - Running post-run listener: _resource_record_post_run 06:08:15 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 327204864; Read time: 16; Write time: 433648 06:08:15 INFO - TinderboxPrint: CPU usage
13.0% 06:08:15 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 06:08:15 INFO - TinderboxPrint: I/O write bytes / time
327,204,864 / 433,648 06:08:15 INFO - TinderboxPrint: CPU idle
8,266.9 (87.0%) 06:08:15 INFO - TinderboxPrint: CPU user
1,189.4 (12.5%) 06:08:15 INFO - TinderboxPrint: Swap in / out
0 / 0 06:08:15 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78835712; Read time: 0; Write time: 164516 06:08:15 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 247754752; Read time: 0; Write time: 269132 06:08:16 INFO - Running post-run listener: _upload_blobber_files 06:08:16 INFO - Blob upload gear active. 06:08:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:08:16 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 06:08:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:08:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:08:16 INFO - (blobuploader) - INFO - Open directory for files ... 06:08:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:08:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:08:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:20 INFO - (blobuploader) - INFO - Done attempting. 06:08:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:08:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:08:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:21 INFO - (blobuploader) - INFO - Done attempting. 06:08:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:08:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:08:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:21 INFO - (blobuploader) - INFO - Done attempting. 06:08:21 INFO - (blobuploader) - INFO - Iteration through files over. 06:08:21 INFO - Return code: 0 06:08:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:08:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:08:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08a50f216dfcb57fe2ca9f8be8983c45ac2d81f3d7709fb524489f57a083d3f121fdb55aa24ab5f38cd29a3cd12ad8ca528f40fbc7da5b5b766cca56ac39d3d3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ce34c325168d55589bc8efc5027a2d110f50c2f6a56d4d7ca4d0f872c4866e8f605634255700abe7d43d8464d3697fb063613866777f741681fa3c8f59095e1c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9cdb71e25d5ae86b6389fce99e9f9e12c9fc47813720a5aeea8ab747083cbe23b55d8b78857caa906b92984049b684e19e43926a8cdc25a2db8bfc1e23bc4ec1"} 06:08:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:08:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:08:21 INFO - Contents: 06:08:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08a50f216dfcb57fe2ca9f8be8983c45ac2d81f3d7709fb524489f57a083d3f121fdb55aa24ab5f38cd29a3cd12ad8ca528f40fbc7da5b5b766cca56ac39d3d3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ce34c325168d55589bc8efc5027a2d110f50c2f6a56d4d7ca4d0f872c4866e8f605634255700abe7d43d8464d3697fb063613866777f741681fa3c8f59095e1c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9cdb71e25d5ae86b6389fce99e9f9e12c9fc47813720a5aeea8ab747083cbe23b55d8b78857caa906b92984049b684e19e43926a8cdc25a2db8bfc1e23bc4ec1"} 06:08:21 INFO - Running post-run listener: copy_logs_to_upload_dir 06:08:21 INFO - Copying logs to upload dir... 06:08:21 INFO - mkdir: /builds/slave/test/build/upload/logs 06:08:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1680.617418 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-09-08 06:08:20.659884) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 06:08:20.663081) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08a50f216dfcb57fe2ca9f8be8983c45ac2d81f3d7709fb524489f57a083d3f121fdb55aa24ab5f38cd29a3cd12ad8ca528f40fbc7da5b5b766cca56ac39d3d3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ce34c325168d55589bc8efc5027a2d110f50c2f6a56d4d7ca4d0f872c4866e8f605634255700abe7d43d8464d3697fb063613866777f741681fa3c8f59095e1c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9cdb71e25d5ae86b6389fce99e9f9e12c9fc47813720a5aeea8ab747083cbe23b55d8b78857caa906b92984049b684e19e43926a8cdc25a2db8bfc1e23bc4ec1"} build_url:https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011430 build_url: 'https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08a50f216dfcb57fe2ca9f8be8983c45ac2d81f3d7709fb524489f57a083d3f121fdb55aa24ab5f38cd29a3cd12ad8ca528f40fbc7da5b5b766cca56ac39d3d3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ce34c325168d55589bc8efc5027a2d110f50c2f6a56d4d7ca4d0f872c4866e8f605634255700abe7d43d8464d3697fb063613866777f741681fa3c8f59095e1c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9cdb71e25d5ae86b6389fce99e9f9e12c9fc47813720a5aeea8ab747083cbe23b55d8b78857caa906b92984049b684e19e43926a8cdc25a2db8bfc1e23bc4ec1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 06:08:20.692103) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 06:08:20.692779) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1473338419.331092-675341019 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004447 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 06:08:20.771722) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 06:08:20.772074) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 06:08:20.772387) ========= ========= Total master_lag: 0.14 =========