builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-006 starttime: 1471508030.96 results: success (0) buildid: 20160817221914 builduid: 8a42d84b4c664f7f898715475fc754b5 revision: d23f68f5cb77934e673700bfaaaf607893a2df9b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 01:13:50.965732) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 01:13:50.966322) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 01:13:50.966600) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 01:13:50.997269) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 01:13:50.997557) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-18 01:13:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 137M=0s 2016-08-18 01:13:51 (137 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093705 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 01:13:51.108758) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 01:13:51.109065) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018218 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 01:13:51.158867) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 01:13:51.159214) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d23f68f5cb77934e673700bfaaaf607893a2df9b --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 d23f68f5cb77934e673700bfaaaf607893a2df9b --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=e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-18 01:13:51,344 truncating revision to first 12 chars 2016-08-18 01:13:51,344 Setting DEBUG logging. 2016-08-18 01:13:51,344 attempt 1/10 2016-08-18 01:13:51,344 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d23f68f5cb77?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 01:13:51,646 unpacking tar archive at: mozilla-aurora-d23f68f5cb77/testing/mozharness/ program finished with exit code 0 elapsedTime=0.687685 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 01:13:51.860430) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 01:13:51.860771) ========= script_repo_revision: d23f68f5cb77934e673700bfaaaf607893a2df9b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 01:13:51.861191) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 01:13:51.861468) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 01:13:51.872739) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-08-18 01:13:51.873132) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:13:52 INFO - MultiFileLogger online at 20160818 01:13:52 in /builds/slave/test 01:13:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 01:13:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:13:52 INFO - {'append_to_log': False, 01:13:52 INFO - 'base_work_dir': '/builds/slave/test', 01:13:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:13:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:13:52 INFO - 'branch': 'Mozilla-Aurora', 01:13:52 INFO - 'buildbot_json_path': 'buildprops.json', 01:13:52 INFO - 'config_files': ('talos/linux_config.py',), 01:13:52 INFO - 'default_actions': ('clobber', 01:13:52 INFO - 'read-buildbot-config', 01:13:52 INFO - 'download-and-extract', 01:13:52 INFO - 'populate-webroot', 01:13:52 INFO - 'create-virtualenv', 01:13:52 INFO - 'install', 01:13:52 INFO - 'run-tests'), 01:13:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:13:52 INFO - 'download_minidump_stackwalk': True, 01:13:52 INFO - 'download_symbols': 'ondemand', 01:13:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:13:52 INFO - 'tooltool.py': '/tools/tooltool.py', 01:13:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:13:52 INFO - '/tools/misc-python/virtualenv.py')}, 01:13:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:13:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:13:52 INFO - 'installer_path': 'installer.exe', 01:13:52 INFO - 'log_level': 'info', 01:13:52 INFO - 'log_name': 'talos', 01:13:52 INFO - 'log_to_console': True, 01:13:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:13:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:13:52 INFO - 'opt_config_files': (), 01:13:52 INFO - 'pip_index': False, 01:13:52 INFO - 'sps_profile': False, 01:13:52 INFO - 'sps_profile_interval': 0, 01:13:52 INFO - 'suite': 'dromaeojs-e10s', 01:13:52 INFO - 'system_bits': '32', 01:13:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:13:52 INFO - 'title': 'talos-linux64-ix-006', 01:13:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:13:52 INFO - 'use_talos_json': True, 01:13:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:13:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:13:52 INFO - 'work_dir': 'build'} 01:13:52 INFO - [mozharness: 2016-08-18 08:13:52.150505Z] Running clobber step. 01:13:52 INFO - Running pre-action listener: _resource_record_pre_action 01:13:52 INFO - Running main action method: clobber 01:13:52 INFO - rmtree: /builds/slave/test/build 01:13:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:13:53 INFO - Running post-action listener: _resource_record_post_action 01:13:53 INFO - [mozharness: 2016-08-18 08:13:53.120200Z] Finished clobber step (success) 01:13:53 INFO - [mozharness: 2016-08-18 08:13:53.120301Z] Running read-buildbot-config step. 01:13:53 INFO - Running pre-action listener: _resource_record_pre_action 01:13:53 INFO - Running main action method: read_buildbot_config 01:13:53 INFO - Using buildbot properties: 01:13:53 INFO - { 01:13:53 INFO - "project": "", 01:13:53 INFO - "product": "firefox", 01:13:53 INFO - "script_repo_revision": "production", 01:13:53 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 01:13:53 INFO - "repository": "", 01:13:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 01:13:53 INFO - "buildid": "20160817221914", 01:13:53 INFO - "pgo_build": "True", 01:13:53 INFO - "basedir": "/builds/slave/test", 01:13:53 INFO - "buildnumber": 479, 01:13:53 INFO - "slavename": "talos-linux64-ix-006", 01:13:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 01:13:53 INFO - "platform": "ubuntu64_hw", 01:13:53 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 01:13:53 INFO - "branch": "mozilla-aurora", 01:13:53 INFO - "repo_path": "releases/mozilla-aurora", 01:13:53 INFO - "revision": "d23f68f5cb77934e673700bfaaaf607893a2df9b", 01:13:53 INFO - "stage_platform": "linux64-pgo", 01:13:53 INFO - "builduid": "8a42d84b4c664f7f898715475fc754b5", 01:13:53 INFO - "slavebuilddir": "test" 01:13:53 INFO - } 01:13:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 01:13:53 INFO - Running post-action listener: _resource_record_post_action 01:13:53 INFO - [mozharness: 2016-08-18 08:13:53.122757Z] Finished read-buildbot-config step (success) 01:13:53 INFO - [mozharness: 2016-08-18 08:13:53.122847Z] Running download-and-extract step. 01:13:53 INFO - Running pre-action listener: _resource_record_pre_action 01:13:53 INFO - Running main action method: download_and_extract 01:13:53 INFO - mkdir: /builds/slave/test/build/tests 01:13: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')]} 01:13:53 INFO - https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 01:13:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 01:13:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 01:13:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:13:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:13:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 01:13:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 01:13:53 INFO - retry: Failed, sleeping 30 seconds before retrying 01:14:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:14:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 01:14:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 01:14:23 INFO - retry: Failed, sleeping 60 seconds before retrying 01:15:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:15:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 01:15:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 01:15:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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! 01:15:23 INFO - Caught exception: HTTP Error 404: Not Found 01:15:23 INFO - Caught exception: HTTP Error 404: Not Found 01:15:23 INFO - Caught exception: HTTP Error 404: Not Found 01:15:23 INFO - trying https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 01:15:23 INFO - Downloading https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:15:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:15:23 INFO - Downloaded 1361 bytes. 01:15:23 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 01:15:23 INFO - Using the following test package requirements: 01:15:23 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 01:15:23 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 01:15:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 01:15:23 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 01:15:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 01:15:23 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 01:15:23 INFO - u'jsshell-linux-x86_64.zip'], 01:15:23 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 01:15:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 01:15:23 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 01:15:23 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 01:15:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 01:15:23 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 01:15:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 01:15:23 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 01:15:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 01:15:23 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 01:15:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 01:15:23 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:15: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')]} 01:15:23 INFO - https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:15:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 01:15:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 01:15:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:15:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:15:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 01:15:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 01:15:23 INFO - retry: Failed, sleeping 30 seconds before retrying 01:15:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:15:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 01:15:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 01:15:53 INFO - retry: Failed, sleeping 60 seconds before retrying 01:16:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:16:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 01:16:53 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 01:16:53 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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! 01:16:53 INFO - Caught exception: HTTP Error 404: Not Found 01:16:53 INFO - Caught exception: HTTP Error 404: Not Found 01:16:53 INFO - Caught exception: HTTP Error 404: Not Found 01:16:53 INFO - trying https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 01:16:53 INFO - Downloading https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:16:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:16:54 INFO - Downloaded 21766304 bytes. 01:16:54 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'] 01:16:54 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 01:16:55 INFO - Return code: 0 01:16:55 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 01:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:16:55 INFO - https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:16:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 01:16:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 01:16:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:16:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:16:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 01:16:55 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 01:16:55 INFO - retry: Failed, sleeping 30 seconds before retrying 01:17:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:17:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 01:17:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 01:17:25 INFO - retry: Failed, sleeping 60 seconds before retrying 01:18:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:18:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 01:18:25 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 01:18:25 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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! 01:18:25 INFO - Caught exception: HTTP Error 404: Not Found 01:18:25 INFO - Caught exception: HTTP Error 404: Not Found 01:18:25 INFO - Caught exception: HTTP Error 404: Not Found 01:18:25 INFO - trying https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 01:18:25 INFO - Downloading https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:18:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:18:26 INFO - Downloaded 21766304 bytes. 01:18:26 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'] 01:18:26 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 01:18:28 INFO - Return code: 0 01:18: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')]} 01:18:28 INFO - https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 01:18:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 01:18:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 01:18:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:18:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:18:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 01:18:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 01:18:28 INFO - retry: Failed, sleeping 30 seconds before retrying 01:18:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:18:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 01:18:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 01:18:58 INFO - retry: Failed, sleeping 60 seconds before retrying 01:19:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:19:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 01:19:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 01:19:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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! 01:19:58 INFO - Caught exception: HTTP Error 404: Not Found 01:19:58 INFO - Caught exception: HTTP Error 404: Not Found 01:19:58 INFO - Caught exception: HTTP Error 404: Not Found 01:19:58 INFO - trying https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 01:19:58 INFO - Downloading https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:19:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:19:59 INFO - Downloaded 14413723 bytes. 01:19:59 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'] 01:19:59 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 01:19:59 INFO - Return code: 0 01:19:59 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')]} 01:19:59 INFO - https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 01:19:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 01:19:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 01:19:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:19:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:19:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 01:19:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 01:19:59 INFO - retry: Failed, sleeping 30 seconds before retrying 01:20:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:20:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 01:20:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 01:20:29 INFO - retry: Failed, sleeping 60 seconds before retrying 01:21:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:21:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 01:21:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 01:21:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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! 01:21:29 INFO - Caught exception: HTTP Error 404: Not Found 01:21:29 INFO - Caught exception: HTTP Error 404: Not Found 01:21:29 INFO - Caught exception: HTTP Error 404: Not Found 01:21:29 INFO - trying https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 01:21:29 INFO - Downloading https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:21:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 01:21:30 INFO - Downloaded 63746489 bytes. 01:21:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 01:21:30 INFO - mkdir: /builds/slave/test/properties 01:21:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:21:30 INFO - Writing to file /builds/slave/test/properties/build_url 01:21:30 INFO - Contents: 01:21:30 INFO - build_url:https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 01:21:31 INFO - Running post-action listener: _resource_record_post_action 01:21:31 INFO - Running post-action listener: set_extra_try_arguments 01:21:31 INFO - [mozharness: 2016-08-18 08:21:31.587691Z] Finished download-and-extract step (success) 01:21:31 INFO - [mozharness: 2016-08-18 08:21:31.587924Z] Running populate-webroot step. 01:21:31 INFO - Running pre-action listener: _resource_record_pre_action 01:21:31 INFO - Running main action method: populate_webroot 01:21:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:21:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:21:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:21:31 INFO - '--tppagecycles', 01:21:31 INFO - '7'], 01:21:31 INFO - 'tests': ['tsvgm']}}, 01:21:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:21:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:21:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:21:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:21:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:21:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:21:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:31 INFO - 'talos_options': ['--disable-e10s'], 01:21:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:21:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:21:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:31 INFO - 'talos_options': ['--disable-e10s'], 01:21:31 INFO - 'tests': ['damp', 'tps']}, 01:21:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:31 INFO - 'tests': ['damp', 'tps']}, 01:21:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:21:31 INFO - 'tests': ['dromaeo_dom']}, 01:21:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:21:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:21:31 INFO - 'tests': ['basic_compositor_video']}, 01:21:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:21:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:21:31 INFO - 'tests': ['a11yr', 01:21:31 INFO - 'ts_paint', 01:21:31 INFO - 'tpaint', 01:21:31 INFO - 'sessionrestore', 01:21:31 INFO - 'sessionrestore_no_auto_restore', 01:21:31 INFO - 'tabpaint']}, 01:21:31 INFO - 'other-e10s': {'tests': ['a11yr', 01:21:31 INFO - 'ts_paint', 01:21:31 INFO - 'tpaint', 01:21:31 INFO - 'sessionrestore', 01:21:31 INFO - 'sessionrestore_no_auto_restore', 01:21:31 INFO - 'tabpaint']}, 01:21:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:21:31 INFO - 'ts_paint', 01:21:31 INFO - 'tpaint', 01:21:31 INFO - 'sessionrestore', 01:21:31 INFO - 'sessionrestore_no_auto_restore', 01:21:31 INFO - 'tabpaint']}, 01:21:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:21:31 INFO - 'ts_paint', 01:21:31 INFO - 'tpaint', 01:21:31 INFO - 'sessionrestore', 01:21:31 INFO - 'sessionrestore_no_auto_restore', 01:21:31 INFO - 'tabpaint']}, 01:21:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:21:31 INFO - 'tests': ['a11yr', 01:21:31 INFO - 'ts_paint', 01:21:31 INFO - 'tpaint', 01:21:31 INFO - 'sessionrestore', 01:21:31 INFO - 'sessionrestore_no_auto_restore', 01:21:31 INFO - 'tabpaint']}, 01:21:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:21:31 INFO - 'tests': ['a11yr', 01:21:31 INFO - 'ts_paint', 01:21:31 INFO - 'tpaint', 01:21:31 INFO - 'sessionrestore', 01:21:31 INFO - 'sessionrestore_no_auto_restore', 01:21:31 INFO - 'tabpaint']}, 01:21:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:21:31 INFO - 'tests': ['tsvgx', 01:21:31 INFO - 'tsvgr_opacity', 01:21:31 INFO - 'tart', 01:21:31 INFO - 'tscrollx', 01:21:31 INFO - 'cart']}, 01:21:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:21:31 INFO - 'tsvgr_opacity', 01:21:31 INFO - 'tart', 01:21:31 INFO - 'tscrollx', 01:21:31 INFO - 'cart']}, 01:21:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:31 INFO - 'talos_options': ['--disable-e10s'], 01:21:31 INFO - 'tests': ['tp5o']}, 01:21:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:31 INFO - 'tests': ['tp5o']}, 01:21:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:31 INFO - 'talos_options': ['--disable-e10s', 01:21:31 INFO - '--xperf_path', 01:21:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:21:31 INFO - 'tests': ['tp5n']}, 01:21:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:31 INFO - 'talos_options': ['--xperf_path', 01:21:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:21:31 INFO - 'tests': ['tp5n']}}, 01:21:31 INFO - 'talos.zip': {'path': '', 01:21:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:21:31 INFO - Running post-action listener: _resource_record_post_action 01:21:31 INFO - [mozharness: 2016-08-18 08:21:31.604816Z] Finished populate-webroot step (success) 01:21:31 INFO - [mozharness: 2016-08-18 08:21:31.604906Z] Running create-virtualenv step. 01:21:31 INFO - Running pre-action listener: _resource_record_pre_action 01:21:31 INFO - Running main action method: create_virtualenv 01:21:31 INFO - Creating virtualenv /builds/slave/test/build/venv 01:21:31 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 01:21:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:21:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:21:31 INFO - Using real prefix '/usr' 01:21:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:21:32 INFO - Installing distribute.............................................................................................................................................................................................done. 01:21:34 INFO - Installing pip.................done. 01:21:34 INFO - Return code: 0 01:21:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:21:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:34 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 0x265f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291d570>, '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 0x291e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291e900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', '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 01:21:34 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 01:21:34 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 01:21:34 INFO - Using env: {'DISPLAY': ':0', 01:21:34 INFO - 'HOME': '/home/cltbld', 01:21:34 INFO - 'LANG': 'en_US.UTF-8', 01:21:34 INFO - 'LANGUAGE': 'en_US:en', 01:21:34 INFO - 'LOGNAME': 'cltbld', 01:21:34 INFO - 'MAIL': '/var/mail/cltbld', 01:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:34 INFO - 'MOZ_NO_REMOTE': '1', 01:21:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:34 INFO - 'NO_EM_RESTART': '1', 01:21:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:34 INFO - 'PWD': '/builds/slave/test', 01:21:34 INFO - 'SHELL': '/bin/bash', 01:21:34 INFO - 'SHLVL': '1', 01:21:34 INFO - 'TERM': 'linux', 01:21:34 INFO - 'TMOUT': '86400', 01:21:34 INFO - 'USER': 'cltbld', 01:21:34 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', 01:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:34 INFO - '_': '/tools/buildbot/bin/python'} 01:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:34 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 01:21:34 INFO - Cleaning up... 01:21:34 INFO - Return code: 0 01:21:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:21:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:34 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 0x265f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291d570>, '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 0x291e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291e900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', '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 01:21:34 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 01:21:34 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 01:21:34 INFO - Using env: {'DISPLAY': ':0', 01:21:34 INFO - 'HOME': '/home/cltbld', 01:21:34 INFO - 'LANG': 'en_US.UTF-8', 01:21:34 INFO - 'LANGUAGE': 'en_US:en', 01:21:34 INFO - 'LOGNAME': 'cltbld', 01:21:34 INFO - 'MAIL': '/var/mail/cltbld', 01:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:34 INFO - 'MOZ_NO_REMOTE': '1', 01:21:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:34 INFO - 'NO_EM_RESTART': '1', 01:21:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:34 INFO - 'PWD': '/builds/slave/test', 01:21:34 INFO - 'SHELL': '/bin/bash', 01:21:34 INFO - 'SHLVL': '1', 01:21:34 INFO - 'TERM': 'linux', 01:21:34 INFO - 'TMOUT': '86400', 01:21:34 INFO - 'USER': 'cltbld', 01:21:34 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', 01:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:34 INFO - '_': '/tools/buildbot/bin/python'} 01:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:34 INFO - Downloading/unpacking psutil>=3.1.1 01:21:34 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 01:21:34 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 01:21:34 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 01:21:34 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 01:21:34 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 01:21:34 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 01:21:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:21:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:21:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:21:36 INFO - Installing collected packages: psutil 01:21:36 INFO - Running setup.py install for psutil 01:21:36 INFO - building 'psutil._psutil_linux' extension 01:21:36 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 01:21:36 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 01:21:36 INFO - building 'psutil._psutil_posix' extension 01:21:36 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 01:21:37 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 01:21:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:21:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:21:37 INFO - Successfully installed psutil 01:21:37 INFO - Cleaning up... 01:21:37 INFO - Return code: 0 01:21:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:21:37 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')]} 01:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:37 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')]} 01:21:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:37 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 0x265f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291d570>, '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 0x291e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291e900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', '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 01:21:37 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 01:21:37 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 01:21:37 INFO - Using env: {'DISPLAY': ':0', 01:21:37 INFO - 'HOME': '/home/cltbld', 01:21:37 INFO - 'LANG': 'en_US.UTF-8', 01:21:37 INFO - 'LANGUAGE': 'en_US:en', 01:21:37 INFO - 'LOGNAME': 'cltbld', 01:21:37 INFO - 'MAIL': '/var/mail/cltbld', 01:21:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:37 INFO - 'MOZ_NO_REMOTE': '1', 01:21:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:37 INFO - 'NO_EM_RESTART': '1', 01:21:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:37 INFO - 'PWD': '/builds/slave/test', 01:21:37 INFO - 'SHELL': '/bin/bash', 01:21:37 INFO - 'SHLVL': '1', 01:21:37 INFO - 'TERM': 'linux', 01:21:37 INFO - 'TMOUT': '86400', 01:21:37 INFO - 'USER': 'cltbld', 01:21:37 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', 01:21:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:37 INFO - '_': '/tools/buildbot/bin/python'} 01:21:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:21:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:21:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:21:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:21:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:21:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:21:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:21:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:21:39 INFO - Installing collected packages: mozsystemmonitor 01:21:39 INFO - Running setup.py install for mozsystemmonitor 01:21:39 INFO - Successfully installed mozsystemmonitor 01:21:39 INFO - Cleaning up... 01:21:39 INFO - Return code: 0 01:21:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:21:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:39 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 0x265f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291d570>, '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 0x291e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291e900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', '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 01:21:39 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 01:21:39 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 01:21:39 INFO - Using env: {'DISPLAY': ':0', 01:21:39 INFO - 'HOME': '/home/cltbld', 01:21:39 INFO - 'LANG': 'en_US.UTF-8', 01:21:39 INFO - 'LANGUAGE': 'en_US:en', 01:21:39 INFO - 'LOGNAME': 'cltbld', 01:21:39 INFO - 'MAIL': '/var/mail/cltbld', 01:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:39 INFO - 'MOZ_NO_REMOTE': '1', 01:21:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:39 INFO - 'NO_EM_RESTART': '1', 01:21:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:39 INFO - 'PWD': '/builds/slave/test', 01:21:39 INFO - 'SHELL': '/bin/bash', 01:21:39 INFO - 'SHLVL': '1', 01:21:39 INFO - 'TERM': 'linux', 01:21:39 INFO - 'TMOUT': '86400', 01:21:39 INFO - 'USER': 'cltbld', 01:21:39 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', 01:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:39 INFO - '_': '/tools/buildbot/bin/python'} 01:21:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:39 INFO - Downloading/unpacking jsonschema==2.5.1 01:21:39 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 01:21:39 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 01:21:39 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 01:21:39 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 01:21:39 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 01:21:39 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 01:21:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:21:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:21:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:21:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:21:41 INFO - Downloading functools32-3.2.3-2.tar.gz 01:21:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:21:42 INFO - warning: no files found matching '*.txt' 01:21:42 INFO - no previously-included directories found matching 'build' 01:21:42 INFO - no previously-included directories found matching 'dist' 01:21:42 INFO - no previously-included directories found matching '.git*' 01:21:42 INFO - Installing collected packages: jsonschema, functools32 01:21:42 INFO - Running setup.py install for functools32 01:21:42 INFO - warning: no files found matching '*.txt' 01:21:42 INFO - no previously-included directories found matching 'build' 01:21:42 INFO - no previously-included directories found matching 'dist' 01:21:42 INFO - no previously-included directories found matching '.git*' 01:21:42 INFO - Successfully installed jsonschema functools32 01:21:42 INFO - Cleaning up... 01:21:42 INFO - Return code: 0 01:21:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:21:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:42 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 0x265f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291d570>, '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 0x291e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291e900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', '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 01:21:42 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 01:21:42 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 01:21:42 INFO - Using env: {'DISPLAY': ':0', 01:21:42 INFO - 'HOME': '/home/cltbld', 01:21:42 INFO - 'LANG': 'en_US.UTF-8', 01:21:42 INFO - 'LANGUAGE': 'en_US:en', 01:21:42 INFO - 'LOGNAME': 'cltbld', 01:21:42 INFO - 'MAIL': '/var/mail/cltbld', 01:21:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:42 INFO - 'MOZ_NO_REMOTE': '1', 01:21:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:42 INFO - 'NO_EM_RESTART': '1', 01:21:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:42 INFO - 'PWD': '/builds/slave/test', 01:21:42 INFO - 'SHELL': '/bin/bash', 01:21:42 INFO - 'SHLVL': '1', 01:21:42 INFO - 'TERM': 'linux', 01:21:42 INFO - 'TMOUT': '86400', 01:21:42 INFO - 'USER': 'cltbld', 01:21:42 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', 01:21:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:42 INFO - '_': '/tools/buildbot/bin/python'} 01:21:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:21:42 INFO - Cleaning up... 01:21:42 INFO - Return code: 0 01:21:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:21:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:42 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 0x265f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291d570>, '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 0x291e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291e900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', '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 01:21:42 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 01:21:42 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 01:21:42 INFO - Using env: {'DISPLAY': ':0', 01:21:42 INFO - 'HOME': '/home/cltbld', 01:21:42 INFO - 'LANG': 'en_US.UTF-8', 01:21:42 INFO - 'LANGUAGE': 'en_US:en', 01:21:42 INFO - 'LOGNAME': 'cltbld', 01:21:42 INFO - 'MAIL': '/var/mail/cltbld', 01:21:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:42 INFO - 'MOZ_NO_REMOTE': '1', 01:21:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:42 INFO - 'NO_EM_RESTART': '1', 01:21:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:42 INFO - 'PWD': '/builds/slave/test', 01:21:42 INFO - 'SHELL': '/bin/bash', 01:21:42 INFO - 'SHLVL': '1', 01:21:42 INFO - 'TERM': 'linux', 01:21:42 INFO - 'TMOUT': '86400', 01:21:42 INFO - 'USER': 'cltbld', 01:21:42 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', 01:21:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:42 INFO - '_': '/tools/buildbot/bin/python'} 01:21:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:42 INFO - Downloading/unpacking blobuploader==1.2.4 01:21:42 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 01:21:42 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 01:21:42 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 01:21:42 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 01:21:42 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 01:21:42 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 01:21:44 INFO - Downloading blobuploader-1.2.4.tar.gz 01:21:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:21:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:21:44 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 01:21:44 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 01:21:44 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 01:21:44 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 01:21:44 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 01:21:44 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 01:21:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:21:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:21:45 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 01:21:45 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 01:21:45 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 01:21:45 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 01:21:45 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 01:21:45 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 01:21:45 INFO - Downloading docopt-0.6.1.tar.gz 01:21:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:21:45 INFO - Installing collected packages: blobuploader, requests, docopt 01:21:45 INFO - Running setup.py install for blobuploader 01:21:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:21:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:21:45 INFO - Running setup.py install for requests 01:21:45 INFO - Running setup.py install for docopt 01:21:45 INFO - Successfully installed blobuploader requests docopt 01:21:45 INFO - Cleaning up... 01:21:45 INFO - Return code: 0 01:21:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:21:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:45 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 0x265f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291d570>, '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 0x291e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291e900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', '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 01:21:45 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 01:21:45 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 01:21:45 INFO - Using env: {'DISPLAY': ':0', 01:21:45 INFO - 'HOME': '/home/cltbld', 01:21:45 INFO - 'LANG': 'en_US.UTF-8', 01:21:45 INFO - 'LANGUAGE': 'en_US:en', 01:21:45 INFO - 'LOGNAME': 'cltbld', 01:21:45 INFO - 'MAIL': '/var/mail/cltbld', 01:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:45 INFO - 'MOZ_NO_REMOTE': '1', 01:21:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:45 INFO - 'NO_EM_RESTART': '1', 01:21:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:45 INFO - 'PWD': '/builds/slave/test', 01:21:45 INFO - 'SHELL': '/bin/bash', 01:21:45 INFO - 'SHLVL': '1', 01:21:45 INFO - 'TERM': 'linux', 01:21:45 INFO - 'TMOUT': '86400', 01:21:45 INFO - 'USER': 'cltbld', 01:21:45 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', 01:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:45 INFO - '_': '/tools/buildbot/bin/python'} 01:21:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:21:46 INFO - Running setup.py (path:/tmp/pip-EWK1p1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:21:46 INFO - Running setup.py (path:/tmp/pip-I7Uynd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:21:46 INFO - Running setup.py (path:/tmp/pip-BMAP8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:21:46 INFO - Running setup.py (path:/tmp/pip-L50PH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:21:46 INFO - Running setup.py (path:/tmp/pip-Dya6Ig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:21:46 INFO - Running setup.py (path:/tmp/pip-BRHiL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:21:46 INFO - Running setup.py (path:/tmp/pip-jETh0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:21:46 INFO - Running setup.py (path:/tmp/pip-REqJLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:21:46 INFO - Running setup.py (path:/tmp/pip-IOgt6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:21:46 INFO - Running setup.py (path:/tmp/pip-Qqk5Hf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:21:46 INFO - Running setup.py (path:/tmp/pip-wpGOFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:21:46 INFO - Running setup.py (path:/tmp/pip-Qct_63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:21:47 INFO - Running setup.py (path:/tmp/pip-pNDnv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:21:47 INFO - Running setup.py (path:/tmp/pip-PTWK_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:21:47 INFO - Running setup.py (path:/tmp/pip-2llzP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:21:47 INFO - Running setup.py (path:/tmp/pip-B5qhoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:21:47 INFO - Running setup.py (path:/tmp/pip-78awlz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:21:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:21:47 INFO - Running setup.py install for manifestparser 01:21:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:21:47 INFO - Running setup.py install for mozcrash 01:21:47 INFO - Running setup.py install for mozdebug 01:21:47 INFO - Running setup.py install for mozdevice 01:21:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:21:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:21:47 INFO - Running setup.py install for mozfile 01:21:47 INFO - Running setup.py install for mozhttpd 01:21:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:21:47 INFO - Running setup.py install for mozinfo 01:21:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:21:48 INFO - Running setup.py install for mozInstall 01:21:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:21:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:21:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:21:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:21:48 INFO - Running setup.py install for mozleak 01:21:48 INFO - Running setup.py install for mozlog 01:21:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:21:48 INFO - Running setup.py install for moznetwork 01:21:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:21:48 INFO - Running setup.py install for mozprocess 01:21:48 INFO - Running setup.py install for mozprofile 01:21:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:21:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:21:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:21:48 INFO - Running setup.py install for mozrunner 01:21:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:21:48 INFO - Running setup.py install for mozscreenshot 01:21:48 INFO - Running setup.py install for moztest 01:21:49 INFO - Running setup.py install for mozversion 01:21:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:21:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:21:49 INFO - Cleaning up... 01:21:49 INFO - Return code: 0 01:21:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:21:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:21:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:49 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 0x265f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291d570>, '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 0x291e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291e900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', '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 01:21:49 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 01:21:49 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 01:21:49 INFO - Using env: {'DISPLAY': ':0', 01:21:49 INFO - 'HOME': '/home/cltbld', 01:21:49 INFO - 'LANG': 'en_US.UTF-8', 01:21:49 INFO - 'LANGUAGE': 'en_US:en', 01:21:49 INFO - 'LOGNAME': 'cltbld', 01:21:49 INFO - 'MAIL': '/var/mail/cltbld', 01:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:49 INFO - 'MOZ_NO_REMOTE': '1', 01:21:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:49 INFO - 'NO_EM_RESTART': '1', 01:21:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:49 INFO - 'PWD': '/builds/slave/test', 01:21:49 INFO - 'SHELL': '/bin/bash', 01:21:49 INFO - 'SHLVL': '1', 01:21:49 INFO - 'TERM': 'linux', 01:21:49 INFO - 'TMOUT': '86400', 01:21:49 INFO - 'USER': 'cltbld', 01:21:49 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', 01:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:49 INFO - '_': '/tools/buildbot/bin/python'} 01:21:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:21:49 INFO - Running setup.py (path:/tmp/pip-vL7mG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:21:49 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)) 01:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:21:49 INFO - Running setup.py (path:/tmp/pip-_voGj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:21:49 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)) 01:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:21:49 INFO - Running setup.py (path:/tmp/pip-4xEEhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:21:49 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)) 01:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:21:49 INFO - Running setup.py (path:/tmp/pip-u8tMEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:21:49 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)) 01:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:21:49 INFO - Running setup.py (path:/tmp/pip-xu4Rww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:21:49 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)) 01:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:21:49 INFO - Running setup.py (path:/tmp/pip-UOWwAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:21:49 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)) 01:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:21:49 INFO - Running setup.py (path:/tmp/pip-P10Eo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:21:49 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)) 01:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:21:49 INFO - Running setup.py (path:/tmp/pip-Id7ndo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:21:49 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)) 01:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:21:49 INFO - Running setup.py (path:/tmp/pip-XXIMQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:21:50 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)) 01:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:21:50 INFO - Running setup.py (path:/tmp/pip-UbhxlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:21:50 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)) 01:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:21:50 INFO - Running setup.py (path:/tmp/pip-8gxDZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:21:50 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)) 01:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:21:50 INFO - Running setup.py (path:/tmp/pip-KcO5D8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:21:50 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)) 01:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:21:50 INFO - Running setup.py (path:/tmp/pip-MZdlX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:21:50 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)) 01:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:21:50 INFO - Running setup.py (path:/tmp/pip-IIUiJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:21:50 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)) 01:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:21:50 INFO - Running setup.py (path:/tmp/pip-rpfcmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:21:50 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)) 01:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:21:50 INFO - Running setup.py (path:/tmp/pip-m2YBCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:21:50 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)) 01:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:21:50 INFO - Running setup.py (path:/tmp/pip-dLXjRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:21:50 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)) 01:21:50 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)) 01:21:50 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)) 01:21:50 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)) 01:21:50 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)) 01:21:50 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)) 01:21:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:21:50 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 01:21:50 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 01:21:50 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 01:21:50 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 01:21:50 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 01:21:50 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 01:21:52 INFO - Downloading blessings-1.6.tar.gz 01:21:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:21:52 INFO - Installing collected packages: blessings 01:21:52 INFO - Running setup.py install for blessings 01:21:52 INFO - Successfully installed blessings 01:21:52 INFO - Cleaning up... 01:21:52 INFO - Return code: 0 01:21:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:21:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:21:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:21:52 INFO - Reading from file tmpfile_stdout 01:21:52 INFO - Current package versions: 01:21:52 INFO - argparse == 1.2.1 01:21:52 INFO - blessings == 1.6 01:21:52 INFO - blobuploader == 1.2.4 01:21:52 INFO - docopt == 0.6.1 01:21:52 INFO - functools32 == 3.2.3-2 01:21:52 INFO - jsonschema == 2.5.1 01:21:52 INFO - manifestparser == 1.1 01:21:52 INFO - mozInstall == 1.12 01:21:52 INFO - mozcrash == 0.17 01:21:52 INFO - mozdebug == 0.1 01:21:52 INFO - mozdevice == 0.48 01:21:52 INFO - mozfile == 1.2 01:21:52 INFO - mozhttpd == 0.7 01:21:52 INFO - mozinfo == 0.9 01:21:52 INFO - mozleak == 0.1 01:21:52 INFO - mozlog == 3.3 01:21:52 INFO - moznetwork == 0.27 01:21:52 INFO - mozprocess == 0.23 01:21:52 INFO - mozprofile == 0.28 01:21:52 INFO - mozrunner == 6.12 01:21:52 INFO - mozscreenshot == 0.1 01:21:52 INFO - mozsystemmonitor == 0.3 01:21:52 INFO - moztest == 0.7 01:21:52 INFO - mozversion == 1.4 01:21:52 INFO - psutil == 3.1.1 01:21:52 INFO - requests == 1.2.3 01:21:52 INFO - wsgiref == 0.1.2 01:21:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:21:52 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')]} 01:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:52 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')]} 01:21:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:52 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 0x265f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291d570>, '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 0x291e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291e900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', '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 01:21:52 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 01:21:52 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 01:21:52 INFO - Using env: {'DISPLAY': ':0', 01:21:52 INFO - 'HOME': '/home/cltbld', 01:21:52 INFO - 'LANG': 'en_US.UTF-8', 01:21:52 INFO - 'LANGUAGE': 'en_US:en', 01:21:52 INFO - 'LOGNAME': 'cltbld', 01:21:52 INFO - 'MAIL': '/var/mail/cltbld', 01:21:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:52 INFO - 'MOZ_NO_REMOTE': '1', 01:21:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:52 INFO - 'NO_EM_RESTART': '1', 01:21:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:52 INFO - 'PWD': '/builds/slave/test', 01:21:52 INFO - 'SHELL': '/bin/bash', 01:21:52 INFO - 'SHLVL': '1', 01:21:52 INFO - 'TERM': 'linux', 01:21:52 INFO - 'TMOUT': '86400', 01:21:52 INFO - 'USER': 'cltbld', 01:21:52 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', 01:21:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:52 INFO - '_': '/tools/buildbot/bin/python'} 01:21:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:53 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)) 01:21:53 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)) 01:21:53 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)) 01:21:53 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)) 01:21:53 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)) 01:21:53 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)) 01:21:53 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)) 01:21:53 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)) 01:21:53 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)) 01:21:53 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)) 01:21:53 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)) 01:21:53 INFO - Cleaning up... 01:21:53 INFO - Return code: 0 01:21:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:21: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')]} 01:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21: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')]} 01:21:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x265f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291d570>, '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 0x291e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291e900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', '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 01:21:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:21:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:21:53 INFO - Using env: {'DISPLAY': ':0', 01:21:53 INFO - 'HOME': '/home/cltbld', 01:21:53 INFO - 'LANG': 'en_US.UTF-8', 01:21:53 INFO - 'LANGUAGE': 'en_US:en', 01:21:53 INFO - 'LOGNAME': 'cltbld', 01:21:53 INFO - 'MAIL': '/var/mail/cltbld', 01:21:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:53 INFO - 'MOZ_NO_REMOTE': '1', 01:21:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:53 INFO - 'NO_EM_RESTART': '1', 01:21:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:53 INFO - 'PWD': '/builds/slave/test', 01:21:53 INFO - 'SHELL': '/bin/bash', 01:21:53 INFO - 'SHLVL': '1', 01:21:53 INFO - 'TERM': 'linux', 01:21:53 INFO - 'TMOUT': '86400', 01:21:53 INFO - 'USER': 'cltbld', 01:21:53 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', 01:21:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:53 INFO - '_': '/tools/buildbot/bin/python'} 01:21:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:21:53 INFO - Cleaning up... 01:21:53 INFO - Return code: 0 01:21:53 INFO - Running post-action listener: _resource_record_post_action 01:21:53 INFO - Running post-action listener: _start_resource_monitoring 01:21:53 INFO - Starting resource monitoring. 01:21:53 INFO - [mozharness: 2016-08-18 08:21:53.448877Z] Finished create-virtualenv step (success) 01:21:53 INFO - [mozharness: 2016-08-18 08:21:53.449374Z] Running install step. 01:21:53 INFO - Running pre-action listener: _resource_record_pre_action 01:21:53 INFO - Running main action method: install 01:21:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:21:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:21:53 INFO - Reading from file tmpfile_stdout 01:21:53 INFO - Detecting whether we're running mozinstall >=1.0... 01:21:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:21:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:21:53 INFO - Reading from file tmpfile_stdout 01:21:53 INFO - Output received: 01:21:53 INFO - Usage: mozinstall [options] installer 01:21:53 INFO - Options: 01:21:53 INFO - -h, --help show this help message and exit 01:21:53 INFO - -d DEST, --destination=DEST 01:21:53 INFO - Directory to install application into. [default: 01:21:53 INFO - "/builds/slave/test"] 01:21:53 INFO - --app=APP Application being installed. [default: firefox] 01:21:53 INFO - mkdir: /builds/slave/test/build/application 01:21:53 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'] 01:21:53 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 01:22:10 INFO - Reading from file tmpfile_stdout 01:22:10 INFO - Output received: 01:22:10 INFO - /builds/slave/test/build/application/firefox/firefox 01:22:10 INFO - Running post-action listener: _resource_record_post_action 01:22:10 INFO - [mozharness: 2016-08-18 08:22:10.254938Z] Finished install step (success) 01:22:10 INFO - [mozharness: 2016-08-18 08:22:10.255278Z] Running run-tests step. 01:22:10 INFO - Running pre-action listener: _resource_record_pre_action 01:22:10 INFO - Running main action method: run_tests 01:22:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:22:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:22:10 INFO - Python 2.7.3 01:22:10 INFO - Return code: 0 01:22:10 INFO - grabbing minidump binary from tooltool 01:22:10 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')]} 01:22:10 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 0x291d570>, '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 0x291e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291e900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:22:10 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 01:22:10 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 01:22:10 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 01:22:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:22:10 INFO - Return code: 0 01:22:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:22:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:22:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:22:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:22:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:22:10 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 01:22:10 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 01:22:10 INFO - Using env: {'DISPLAY': ':0', 01:22:10 INFO - 'HOME': '/home/cltbld', 01:22:10 INFO - 'LANG': 'en_US.UTF-8', 01:22:10 INFO - 'LANGUAGE': 'en_US:en', 01:22:10 INFO - 'LOGNAME': 'cltbld', 01:22:10 INFO - 'MAIL': '/var/mail/cltbld', 01:22:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:22:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:22:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:10 INFO - 'MOZ_NO_REMOTE': '1', 01:22:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:22:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:10 INFO - 'NO_EM_RESTART': '1', 01:22:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:10 INFO - 'PWD': '/builds/slave/test', 01:22:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:22:10 INFO - 'SHELL': '/bin/bash', 01:22:10 INFO - 'SHLVL': '1', 01:22:10 INFO - 'TERM': 'linux', 01:22:10 INFO - 'TMOUT': '86400', 01:22:10 INFO - 'USER': 'cltbld', 01:22:10 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541', 01:22:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:10 INFO - '_': '/tools/buildbot/bin/python'} 01:22:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 01:22:10 INFO - mozversion application_buildid: 20160817221914 01:22:10 INFO - mozversion application_changeset: d23f68f5cb77934e673700bfaaaf607893a2df9b 01:22:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:22:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:22:10 INFO - mozversion application_name: Firefox 01:22:10 INFO - mozversion application_remotingname: firefox-dev 01:22:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:22:10 INFO - mozversion application_vendor: Mozilla 01:22:10 INFO - mozversion application_version: 50.0a2 01:22:10 INFO - mozversion platform_buildid: 20160817221914 01:22:10 INFO - mozversion platform_changeset: d23f68f5cb77934e673700bfaaaf607893a2df9b 01:22:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:22:10 INFO - mozversion platform_version: 50.0a2 01:22:10 INFO - using testdate: 1471508530 01:22:10 INFO - actual date: 1471508530 01:22:10 INFO - starting webserver on 'localhost:56889' 01:22:10 INFO - SUITE-START | Running 2 tests 01:22:10 INFO - TEST-START | dromaeo_css 01:22:10 INFO - operating with platform_type : linux_ 01:22:10 INFO - Initialising browser for dromaeo_css test... 01:22:10 INFO - TEST-INFO | started process 12539 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHFZfue/profile http://localhost:56889/getInfo.html) 01:22:14 INFO - PROCESS | 12539 | __metrics Screen width/height:1600/1200 01:22:14 INFO - PROCESS | 12539 | colorDepth:24 01:22:14 INFO - PROCESS | 12539 | Browser inner width/height: 1024/697 01:22:14 INFO - PROCESS | 12539 | __metrics 01:22:15 ERROR - PROCESS | 12539 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 01:22:16 INFO - TEST-INFO | 12539: exit 0 01:22:16 INFO - Browser initialized. 01:22:16 INFO - Running cycle 1/1 for dromaeo_css test... 01:22:16 INFO - TEST-INFO | started process 12665 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHFZfue/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:22:28 INFO - PROCESS | 12665 | RSS: Main: 152383488 01:22:28 INFO - PROCESS | 12665 | 01:23:18 INFO - PROCESS | 12665 | 1471508598005 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] 01:25:26 INFO - PROCESS | 12665 | Cycle 1(1): loaded http://localhost:56889/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56889/tests/dromaeo/cssquery-ext.html) 01:25:27 INFO - PROCESS | 12665 | RSS: Main: 159006720 01:25:27 INFO - PROCESS | 12665 | 01:28:29 INFO - PROCESS | 12665 | Cycle 1(1): loaded http://localhost:56889/tests/dromaeo/cssquery-ext.html (next: http://localhost:56889/tests/dromaeo/cssquery-jquery.html) 01:28:30 INFO - PROCESS | 12665 | RSS: Main: 153591808 01:28:30 INFO - PROCESS | 12665 | 01:31:32 INFO - PROCESS | 12665 | Cycle 1(1): loaded http://localhost:56889/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56889/tests/dromaeo/cssquery-mootools.html) 01:31:32 INFO - PROCESS | 12665 | RSS: Main: 154079232 01:31:32 INFO - PROCESS | 12665 | 01:34:34 INFO - PROCESS | 12665 | Cycle 1(1): loaded http://localhost:56889/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56889/tests/dromaeo/cssquery-prototype.html) 01:34:35 INFO - PROCESS | 12665 | RSS: Main: 153255936 01:34:35 INFO - PROCESS | 12665 | 01:37:37 INFO - PROCESS | 12665 | Cycle 1(1): loaded http://localhost:56889/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56889/tests/dromaeo/cssquery-yui.html) 01:37:38 INFO - PROCESS | 12665 | RSS: Main: 155230208 01:37:38 INFO - PROCESS | 12665 | 01:40:40 INFO - PROCESS | 12665 | Cycle 1(1): loaded http://localhost:56889/tests/dromaeo/cssquery-yui.html (next: http://localhost:56889/tests/dromaeo/cssquery-dojo.html) 01:40:41 INFO - PROCESS | 12665 | RSS: Main: 155361280 01:40:41 INFO - PROCESS | 12665 | 01:40:41 INFO - PROCESS | 12665 | __start_tp_report 01:40:41 INFO - PROCESS | 12665 | _x_x_mozilla_page_load 01:40:41 INFO - PROCESS | 12665 | _x_x_mozilla_page_load_details 01:40:41 INFO - PROCESS | 12665 | |i|pagename|runs| 01:40:41 INFO - PROCESS | 12665 | |0;dojo.html;3318.236805534728;3324.484704870745;3341.8997430077093;3368;3371.730261579074;5839.065749480084;5843.79546715865;5869.794557190499;5905.025670764318;5912.7930522431725;5762.654240745558;5769.7692092316365;5804.5936784425;5807.941920580793;5808.941910580893;5219.086659834533;5281.63028587999;5285.207218917155;5290.603204759639;5310.548603368718;5100.9489905100845;5119.206522988943;5150.8712282193055;5243.947560524384;5288.391834938992;5286.603504737141;5322.669332666834;5328.17413300939;5347.037533244014;5379.327584051994;5285.207218917174;5293.23248129023;5299.735013249341;5306.6550674206055;5322.600804939606;4983.526564976321;5114.28400023997;5219.765110570033;5239.056969745444;5288.471152884719;5706.77172913083;5791.131330300446;5814.418558144194;5815.912761308584;5817.301923769132;6182.938170618281;6219.160413344186;6219.689015549205;6232.376762323776;6253.937460625381;5973.1040343948325;6145.539084568675;6302.968485157545;6308.085327627514;6319.178506794087;5773.191753154561;5825.737841797087;5830.941690583039;5858.209141765849;5862.853428664317;6344.397282258178;6370.58591191571;6385.712642931079;6390.45681117101;6391.201099862517;5929.199558059693;6083.482903953125;6102.572819902564;6169.96915015431;6182.28903676074;6178.135061091451;6303.086052522359;6334.5249106317215;6342.270638876588;6342.714577844008;6205;6288.968555157195;6301.747930082837;6303.779367722108;6304;5972.462478376967;6061.575689701678;6290.025046117859;6323;6324.9051264231075;4651.348811166439;4695.201815691341;4697.530246975275;4717.386739723883;4724.71651700899;4191.580841915784;4197.9370309445385;4199.727017743837;4210.59999300066;4228.9788551057045;5830.504407125357;5847.8245652630485;5877.147813567009;5924.9703751480965;5932.851678707981;6195.070739389038;6298.086777417248;6315.842103947438;6329.4303512683155;6332.050192471166;6022.277326720734;6044.848878777978;6057.454829065406;6070.362611925772;6077.17958075663;4986.476419975923;5110.054639891632;5219.217117432416;5229.816956406507;5247.370315562081;6276.560640755195;6395.648239346788;6415.941369673951;6439.066335381343;6453.741850326029;6256.092866534418;6266.436020758154;6269.5611307208055;6272.905906411499;6274.278457977391;6176.938230617637;6272.905906411316;6362.554621176473;6429.7749578764515;6435.1312572803945;6025.698715064317;6139.430809034469;6151.384861513813;6151.9384806151365;6159.692015399301;6244;6254.8123556293385;6264.248290205204;6273.9372606273355;6280.214973128359;5911.911321330097;6068.059450785136;6123.234595675541;6128.264608247039;6140.232470941132;6006.738584897046;6113.867421735523;6115.4408910389475;6131.5295901671125;6132.421572786907;6011.458968692838;6021.819345419644;6055.303640081271;6064.3935606439;6074.210352654127;5952.76188952437;6050.1227322037175;6124.356942521149;6174.506039516918;6201.627902325875;5897.970510147422;5988.730507127103;6010.038393856962;6014.1580178775885;6042.0936859469675 01:40:41 INFO - PROCESS | 12665 | |1;ext.html;585.4789237578553;631.7378288010475;660.4221306356939;663.5288944849157;717.4117223876417;41130;46751.76624116879;46961.765191173996;46997.53002469979;47038;47619.285710714365;48245;48273.51726482743;48376;48647;9189.264858811295;9224;9249.583768730423;9317.720468385925;9346.766330841749;9150.72547823563;9185.40294880834;9216.723498295061;9231.861522077174;9266.166045055943;9146.86279705805;9243.722688319362;9275.118863707938;9296.7675808105;9312.674056408026;9258.861117083248;9302;9310.115539023782;9311.627534898596;9314.86027709585;9185.632574697003;9301.255899528021;9308.44149351041;9339.579718912597;9345.112214339626;44358.33462498065;45076.774616126706;45207.321890171675;45207.54792452099;45241.321380179324;3875.418687196915;3947.388154836005;3967.147063381387;3982.482277303961;4021.577734337911;34839;34878.65121348779;35633.64366356329;35689;35899.64100359015;4850.102730994778;4867.123917694814;5016.372953380827;5023.447420783711;5097.362829646294;388414;391232;394737;396044;396477;61779.6911015442;61829.38170618326;61839.38160618426;61977.38022619806;62058.37941620616;39407.80296098501;40041.39937900934;40337;41905;42162;18997;19069.61860762777;19085.713714294296;19105.426837194907;19137.2345106197;14662.120272783453;14774.113553186844;14809.481668141563;14816.851831481763;14884.404623815142;21086.578268434547;21126.57746845085;21220.36338909835;21387;21416.78583214179;7697.692092316357;7721.343685786771;7828.725994590162;8024.79938001543;8031.83936321282;1053.5417093564324;1060.6446840308583;1065.6643157405392;1068.8289873620183;1219.4573414830493;34383.484247736305;34536;34591.30817383638;34972.65027349694;35200.82399588037;1161.7560312334335;1166.9649910502699;1198.5265820001225;1243.657994051643;1270.955516556916;17546.912265438845;17980.2807887683;18028.45914622537;18149.54626134331;18428.539286517676;667394;674397;680320;682654;690861;66951;67017.32982670209;67130.3286967124;67553.32446675569;67569;1462.1154201708025;1476.5496523560416;1479.7706355514697;1487.3530014443752;1516.7421538338508;35211;35577.110572234866;35652.64347356493;35810.64189358177;35880.64119358773;57154;57675;57773.71113144408;57907;58003;542084;546566;549658;551894;552503;31018.72681644081;31230.21924451907;32039;32166.83916580402;32315.19212019718;52232;52924.47075529196;53110.203346949056;53296.73351633217;53923.73038134784;44863.775681121384;45027;45045.774771125936;46459.767701161276;47940.7602961983;47035.294470583656;47766.761166193945;47786.522134778206;48125.6423304487;48143.5185648139;40147;44485;45114.323285150094;45199.32201016921;46095.07809843851;38974.41538376983;40474.797626011685;41078.235253137136;41242.38136427896;41606;31262.53106203454;31424;31672.841635791672;31831.363372731954;31876.203094922814 01:40:41 INFO - PROCESS | 12665 | |2;jquery.html;407.6718241815339;407.7553467919247;422.6407553579457;423.90674051708623;438.24840398716196;20652.48368790781;20653.896730516346;21346.35960921173;21648.2423115191;21838.45403864904;17177.79755417121;19742;20204.393868183943;20321.49196270094;21394;5285.101532739424;5433.26650902129;5435.728213589305;5477.20580515825;5489.039418101836;5313.123334649778;5414.07960646689;5415.620906536544;5436.21174929635;5457.754401051942;5300.655457395276;5395.460453954613;5430.5384042356245;5443.156310771837;5444.91832622511;5356.785728570852;5434.184872269152;5439.673619582818;5698.6580805151825;5897.115432685132;5819.53443724501;5823.621464604829;5882.441168089024;5883.3234178069215;5901.498372638331;14480.927595362062;14580.708385832224;14890.702185956328;14965.850341496554;14970.625734356729;2358.9646155307682;2424.7211570669424;2452.2520631207476;2474.183519438581;2506.7994560435122;11232;11260.887391126065;11326.886731132749;13941.790873136912;15028.924855375762;1894.355918987551;1984.0674882805204;2037.8573499855015;2047.991431164181;2092.633789086919;79539;80263.04628756442;83445;83906.58046709727;84023;11476.770464590662;11816.645500634995;11921.403929803475;12344.876551234553;12657.746845063048;16809.487146156906;16863;16900.915495422443;16918.30816918323;16948.322067117424;8956.552172391355;8966.506842123616;8990.595423205972;9090.545472726337;9258.629654813867;8797.736067917973;8842.602082906285;8916.420432671855;9010.549472526347;9107.81784364309;5015.724135172601;5084.618653600995;5157.690538567698;5162.277281180638;5227.503387178212;2035.1452389996236;2100.9684854727193;2136.978630213709;2138.7219661443914;2168.273628334494;918.8162367526521;1005.3465247588979;1056.144522936424;1057.7302787789065;1087.7987572298991;20679;21135;21279;21470.355889323346;21526.56946861054;1058.1059005140644;1082.604849230041;1085.918556108295;1095.0965453500894;1108.6009036746764;10922.1808364373;11711.472983715754;11797.29216247028;11840.171188016757;11991.280523168638;472944;480452;481664;508600;509323;61102.69448652817;94601;95033;95341;99539.50230248948;1056.3609016545033;1087.287826473677;1098.5441041967654;1103.2222283290262;1112.609238451935;16048.358065677243;18392.632147357246;18591.72112418342;18750.812491874905;19071;42686.35970460508;43802.561974379845;45531.77234113808;45690.77154614206;46489.07021859611;64674.676626616565;64840.35159648532;65010.67494662497;66185;66736.33263667495;9191.678291259774;9250.676226332045;9442.95278523603;9516.714498565054;9618.807623847344;13309.134906231064;13862.722745545234;14053.929730351283;14067.437302508195;14248.28758562047;12233.816492752794;12446.128770986306;12576.87423125757;12583.119181657195;12784.6803829905;12143.453544590338;12293.631191063925;12349.024156134976;12398.690032749253;12636.87363126357;12505.062120341192;12570.748585028065;12631.368431578569;12765.234085954873;12947.935260324015;9103.863442048241;9208.631654733736;9269.073092690676;9310;9331.206847418045;11016.504257308601;12386.132970691964;12450.626481205578;12491;12776.936115319364 01:40:41 INFO - PROCESS | 12665 | |3;mootools.html;1530.0590137065703;1571.7170909236336;1754.5175076853864;1852.9444116676489;1869.345728994852;8826.426282291646;9366.906330936697;9418.670346537872;10141.594336226552;10157.796844063114;9508.334416590824;9743.853842192373;9751.951240243807;9765.779277590302;9850.310478266525;2543.1226226951717;2583.1217386088724;2595.623634572986;2600.9739902601013;2608.8304260223026;2508.9372765680823;2600.388908606476;2621.043319188492;2623.973760262392;2624.724403937588;2503.7371076037025;2542.211914306571;2550.2349295211457;2553.0298486575075;2586.3921978335075;2506.147909710698;2509.8494090354548;2510.698716154055;2539.1240022192314;2569.820112592119;2482.7229350860002;2503.6119401492797;2506.724260331362;2567.653366795496;2572.957952029431;9974.251931105196;10059.647912323107;10064.89935100647;10069.798604027952;10082;5854.18041474194;6001.099835024781;6034.758609655609;6038.064100064497;6044.032954727222;3703.925921481582;3724.4413337999245;3781.621837816224;3811.3330167220624;3943.9014024649327;1167.7430965187646;1195.6293548999752;1204.090911361925;1207.9033677305795;1273.0642977411594;13438.328083595781;13607.387667554984;13796.793048104286;14120.505782297672;14245.501407450689;4977.800887964441;4999.575036121971;5149.871253218699;5190.6107041972;5206.01085793698;10896;10921.563137474412;10921.563137474412;10927.3989930553;10934.289271197347;5769.365369809401;5811.593188476851;5813.767449302065;5814.941850581525;5831.7375718091935;4924.630652701062;5360.3031605891765;5450.863728406822;5493.917591236134;5511.3937466878615;4238.23711731885;4251.489821221473;4275.978620106879;4304.859212308678;4358.128374325148;1264.6016504800957;1265.240855486719;1285.7299967007034;1298.214580178997;1330.60081975407;940.6331530703031;1094.6934858239706;1101.8787933327328;1103.7185517692938;1118.111101674171;6465.4827613790785;6516.967415162989;6543.247526534509;6581.934180658228;6629;481.8891654919388;482.14900700264064;484.791539637952;488.3529323646183;492.48043314303754;4520.118576877543;4520.389747384127;4556.066006468666;4585.701929374647;4608.239640459354;11929.224600400947;12189;12316;12322.876771232173;12332.260064396165;11741.412929353499;12250.571230006906;12299.569515066929;12506.49974001048;12548.309842958543;9138.81722365549;9223.446593204295;9234.399764015452;9306.813863722688;9364.578593963288;6522.086907832812;6645.634490103091;6653.634050127096;6678.632675202911;6687.966560167168;11357.54569817198;11368.261063030877;11417.486213120595;11429.199956003;11512.712182195512;11186.776264474502;11386.089112871116;11502.88497115018;11637.825432618349;11766.235194712317;7705.884411733717;8228.794280143044;8420.915790842013;8520.786980325542;8557.229849313342;9593.90406095958;9615.182709469495;9625.422474651543;9626.711198664052;9718.368306060083;8353.289970352345;8617.82764344697;8629;8652.394332396672;8654.956725216334;8144.389170812331;8255.917440825515;8289.461185023196;8316.708915187939;8464.068952415106;7835.098963619029;7905.802354940943;7908.130105688255;7961.124276329715;7997.8400431992195;5672.971635141798;5856.238688970407;5899.646021238739;5920.023196172756;5964.582479226413;5830.2420685310635;5851.034579294369;5896.734646940812;5946.970265148647;5958.1658567801405 01:40:41 INFO - PROCESS | 12665 | |4;prototype.html;347.90954351868515;350.9736769742269;354.30202501072876;354.41344574728834;363.3713675341657;5931.288245410557;5939.762409503625;5950.375210602884;5966.836466888958;6002;5790.739416726247;5809.651420914737;5817.534597232211;5883.735231914563;5890.352061273267;4302.204092242937;4479.171353299634;4495.955040449603;4507.256302710049;4542.591166794988;4499.370088187657;4525.864224073283;4544.954660428103;4551.7951692173765;4558.886027849314;4523.710742438406;4537.296719008543;4551.726896386211;4559.544045595447;4568.154891345095;4397.714148580332;4453.28747400416;4481.148581769469;4490.685652004361;4495.168393847116;3939.172773717523;3952.347862602667;3952.7430717003303;3963.7819919904396;3972.7417717848534;5675.177099320616;5706.800261990852;5720.370759216483;5738.196652468658;5742.913856292159;5052.725825706341;5110.182370820651;5134.358205224347;5159.471858789204;5200.167973124319;6000.989668461071;6001.459868611846;6094.573379863411;6099.359567245419;6121.540884433685;4262.701610887239;4356.89107772305;4357.912841743179;4361.386287073803;4365.279728844736;6021.849453763691;6141.815745527686;6354.523410744213;6372.3309052549275;6378.330275321117;4320.287152619782;4342.848000319974;4350.434443522385;4364.6290065344165;4368.148211098869;4413;4427.335899615019;4433.578810013044;4436.0462500562535;4442.355858400514;4968.453470118284;5064.974675126601;5096.490350964874;5125.1287281162295;5144.254083157921;6019.69901504923;6049.969750151221;6064.818055458343;6122.112293717386;6153.138560601519;1523.8171419429739;1541.884358673104;1547.8452154784657;1548.95353139406;1562.1954693332943;943.7593413679605;987.7234374375187;996.5266498413196;1003.8243307421172;1015.5480811039017;5229.535729995608;5243.344581927259;5253.9492101579835;5258.474152584788;5276.91300589152;5446.754896029609;5566.471185237396;5579.7489112990015;5582.720863956786;5618.949083584544;4879.584605620241;5208.140656791663;5226.5818734501145;5272.9736351318;5295.232191332235;2961.318896653782;2966.896158634481;2975;2978.508546089871;2987.581738556604;5603.439656034364;5610.018246806874;5618.747156377973;5709.229254050733;5728.51307638847;5940.6435613863305;6400.743970241138;6411.743530258737;6472.870542589122;6507.577007494498;6538.972918395312;6542.443892269211;6551.901721474086;6553.311902250195;6556.311587283267;6105.633661980295;6289.27673317556;6341.224457152005;6351.428371446592;6358.841028974312;6194.535409844189;6275.717592708248;6275.905861412174;6279.434850863444;6281.371862813865;6181.629102253879;6412.871742565216;6492.415682588589;6499.155109835691;6521.445677117498;4431.784107946027;4446.466424029008;4466.352378905106;4473.575010374009;4475.194464996332;6281.466075383643;6317.494600432046;6373.8725225496155;6425.614463132227;6452.903206452001;6143.508519318533;6158.907616385668;6205.131281620486;6208.596441231305;6210;5945.583809133492;6120.3682416561305;6151.2618485781995;6197.25632924052;6237.126802247598;6012.849678758066;6070.848228794315;6080.331163572092;6117.663528505975;6145.385461453998;6140.4078614011305;6179.62922224668;6213.502919766317;6225.470834979077;6227.346128656607;5721.828345149652;5993.280806303097;6034.732706131762;6049.183360246487;6059.515238780975 01:40:41 INFO - PROCESS | 12665 | |5;yui.html;841.5245386356708;852.774014886055;853.1937319233323;892.8258989497048;949.753064203307;17577.47267581973;18186.5453363666;18275.543111422223;18441.170147343368;18543.907280463613;15544.922275388635;16000.599985000352;18178.72731909019;18387.90806045971;18395.448136555926;883.7392969074139;890.1410139215656;914.021996463784;916.0198587511366;916.097643820836;905.5245995852198;906.8503696890039;915.3455279475174;920.2591913509622;921.5300196899562;962.3311798300149;967.8257913575553;970.3644113105892;972.2270794718181;975.6536429567508;961.8941916389192;966.1497881863914;969.3166317746008;972.3485264872588;972.9610815567404;704.4188544450798;709.3438569323355;712.262807993724;712.7790384980673;716.989245161323;16807.579810504714;17371.131443427777;17539.912300438416;17588.91205543977;17611.207495662726;15844.653204477634;16232.513024609281;16310.429134980337;16383.262753176135;16692.165391730487;13179.341032948314;13300.800487992688;13359.465621375133;13401.329933503383;13694.246816425093;4177.373393990895;4211.073563815956;4218.198542276958;4253.851115210984;4347.630451411634;78549.21450785476;89185;89308;89642.55178724065;89661.10338896593;12150.513979440724;13048.34758262083;13053.412596433183;13495.662608434672;14041.227732474812;23972.64041039386;24361.512769744506;24383.14658986927;24387;24435.38911527226;9102.13529714676;9219.861702074475;9364.110409511086;9584.041595840361;9617;4809.759512024385;5011.4236862761245;5049.394072711299;5160.896782064413;5228.37259528859;4126.071633882412;4297.290946993773;4314.9784251078545;4338.262495375794;4350.173467041315;728.0644371982083;728.536147172224;733.9633018349168;738.933495985364;744.9515781474187;640.6668532363238;685.0375222811957;754.4643303254641;844.4215712237014;866.696656170348;10227.232957528215;11219.60731374398;11309.886901131047;11334.716632084099;11711.590094346828;815.6533473273835;822.4777266435856;828.0725587342095;831.5177197225595;844.6536919862949;5197.948020519746;5266.4470230626;5339.3058902342445;5353.1167357386375;5383.596230282745;75302;78771.60614196892;138366;138719;143475;118108;118301;119945.40027299807;123185.38407307905;123968.1404778929;104267;104979;106558.46720766346;107779.92220077856;107890.46054769676;9949.403035817873;10070.439081942312;10143.949280253551;10258.743531411774;10259.948700256451;57976.71011644915;59598.404015959284;59708.402915970284;61012.38987610067;61450.07824882541;70445.64777176286;86417.56791216004;86673;87961;90060.09939900516;13328.600141995756;13332.866671333162;13538.93230533841;13555.59333220005;13851.376688049264;14078.158049862757;14377.856221437652;14533.418663253351;14830.777538336717;14921.776173357626;14169.433222670978;14378.28108594587;14697.632559185678;14699.044562103429;14926.776098358316;14014.229217392733;14893.255337232878;15087.698246035237;15500.844991550392;15533.533993980198;13584.52454164139;13603.319834008062;14644.853551464348;14770.556883293519;14891.329890154753;11733.413329333669;11875.465604047666;11944.16390852632;12153.779248499332;12608.747825043265;12216.144869859025;12296.938515307724;12404.069694773108;12516.937415312865;12889.935550322189 01:40:41 INFO - PROCESS | 12665 | __end_tp_report 01:40:41 INFO - PROCESS | 12665 | __start_cc_report 01:40:41 INFO - PROCESS | 12665 | _x_x_mozilla_cycle_collect,316 01:40:41 INFO - PROCESS | 12665 | __end_cc_report 01:40:41 INFO - PROCESS | 12665 | __startTimestamp1471509641042__endTimestamp 01:40:41 INFO - PROCESS | 12665 | 01:40:41 INFO - PROCESS | 12665 | ------- Summary: start ------- 01:40:41 INFO - PROCESS | 12665 | Number of tests: 6 01:40:41 INFO - PROCESS | 12665 | 01:40:41 INFO - PROCESS | 12665 | [#0] dojo.html Cycles:165 Average:5774.53 Median:6022.28 stddev:678.16 (11.3%) stddev-sans-first:652.29 01:40:41 INFO - PROCESS | 12665 | Values: 3318.2 3324.5 3341.9 3368.0 3371.7 5839.1 5843.8 5869.8 5905.0 5912.8 5762.7 5769.8 5804.6 5807.9 5808.9 5219.1 5281.6 5285.2 5290.6 5310.5 5100.9 5119.2 5150.9 5243.9 5288.4 5286.6 5322.7 5328.2 5347.0 5379.3 5285.2 5293.2 5299.7 5306.7 5322.6 4983.5 5114.3 5219.8 5239.1 5288.5 5706.8 5791.1 5814.4 5815.9 5817.3 6182.9 6219.2 6219.7 6232.4 6253.9 5973.1 6145.5 6303.0 6308.1 6319.2 5773.2 5825.7 5830.9 5858.2 5862.9 6344.4 6370.6 6385.7 6390.5 6391.2 5929.2 6083.5 6102.6 6170.0 6182.3 6178.1 6303.1 6334.5 6342.3 6342.7 6205.0 6289.0 6301.7 6303.8 6304.0 5972.5 6061.6 6290.0 6323.0 6324.9 4651.3 4695.2 4697.5 4717.4 4724.7 4191.6 4197.9 4199.7 4210.6 4229.0 5830.5 5847.8 5877.1 5925.0 5932.9 6195.1 6298.1 6315.8 6329.4 6332.1 6022.3 6044.8 6057.5 6070.4 6077.2 4986.5 5110.1 5219.2 5229.8 5247.4 6276.6 6395.6 6415.9 6439.1 6453.7 6256.1 6266.4 6269.6 6272.9 6274.3 6176.9 6272.9 6362.6 6429.8 6435.1 6025.7 6139.4 6151.4 6151.9 6159.7 6244.0 6254.8 6264.2 6273.9 6280.2 5911.9 6068.1 6123.2 6128.3 6140.2 6006.7 6113.9 6115.4 6131.5 6132.4 6011.5 6021.8 6055.3 6064.4 6074.2 5952.8 6050.1 6124.4 6174.5 6201.6 5898.0 5988.7 6010.0 6014.2 6042.1 01:40:41 INFO - PROCESS | 12665 | 01:40:41 INFO - PROCESS | 12665 | [#1] ext.html Cycles:180 Average:70253.43 Median:45207.43 stddev:147371.55 (326.0%) stddev-sans-first:147692.14 01:40:41 INFO - PROCESS | 12665 | Values: 585.5 631.7 660.4 663.5 717.4 41130.0 46751.8 46961.8 46997.5 47038.0 47619.3 48245.0 48273.5 48376.0 48647.0 9189.3 9224.0 9249.6 9317.7 9346.8 9150.7 9185.4 9216.7 9231.9 9266.2 9146.9 9243.7 9275.1 9296.8 9312.7 9258.9 9302.0 9310.1 9311.6 9314.9 9185.6 9301.3 9308.4 9339.6 9345.1 44358.3 45076.8 45207.3 45207.5 45241.3 3875.4 3947.4 3967.1 3982.5 4021.6 34839.0 34878.7 35633.6 35689.0 35899.6 4850.1 4867.1 5016.4 5023.4 5097.4 388414.0 391232.0 394737.0 396044.0 396477.0 61779.7 61829.4 61839.4 61977.4 62058.4 39407.8 40041.4 40337.0 41905.0 42162.0 18997.0 19069.6 19085.7 19105.4 19137.2 14662.1 14774.1 14809.5 14816.9 14884.4 21086.6 21126.6 21220.4 21387.0 21416.8 7697.7 7721.3 7828.7 8024.8 8031.8 1053.5 1060.6 1065.7 1068.8 1219.5 34383.5 34536.0 34591.3 34972.7 35200.8 1161.8 1167.0 1198.5 1243.7 1271.0 17546.9 17980.3 18028.5 18149.5 18428.5 667394.0 674397.0 680320.0 682654.0 690861.0 66951.0 67017.3 67130.3 67553.3 67569.0 1462.1 1476.5 1479.8 1487.4 1516.7 35211.0 35577.1 35652.6 35810.6 35880.6 57154.0 57675.0 57773.7 57907.0 58003.0 542084.0 546566.0 549658.0 551894.0 552503.0 31018.7 31230.2 32039.0 32166.8 32315.2 52232.0 52924.5 53110.2 53296.7 53923.7 44863.8 45027.0 45045.8 46459.8 47940.8 47035.3 47766.8 47786.5 48125.6 48143.5 40147.0 44485.0 45114.3 45199.3 46095.1 38974.4 40474.8 41078.2 41242.4 41606.0 31262.5 31424.0 31672.8 31831.4 31876.2 01:40:41 INFO - PROCESS | 12665 | 01:40:41 INFO - PROCESS | 12665 | [#2] jquery.html Cycles:180 Average:29553.24 Median:21587.41 stddev:80951.98 (375.0%) stddev-sans-first:81149.49 01:40:41 INFO - PROCESS | 12665 | Values: 407.7 407.8 422.6 423.9 438.2 20652.5 20653.9 21346.4 21648.2 21838.5 17177.8 19742.0 20204.4 20321.5 21394.0 5285.1 5433.3 5435.7 5477.2 5489.0 5313.1 5414.1 5415.6 5436.2 5457.8 5300.7 5395.5 5430.5 5443.2 5444.9 5356.8 5434.2 5439.7 5698.7 5897.1 5819.5 5823.6 5882.4 5883.3 5901.5 14480.9 14580.7 14890.7 14965.9 14970.6 2359.0 2424.7 2452.3 2474.2 2506.8 11232.0 11260.9 11326.9 13941.8 15028.9 1894.4 1984.1 2037.9 2048.0 2092.6 79539.0 80263.0 83445.0 83906.6 84023.0 11476.8 11816.6 11921.4 12344.9 12657.7 16809.5 16863.0 16900.9 16918.3 16948.3 8956.6 8966.5 8990.6 9090.5 9258.6 8797.7 8842.6 8916.4 9010.5 9107.8 5015.7 5084.6 5157.7 5162.3 5227.5 2035.1 2101.0 2137.0 2138.7 2168.3 918.8 1005.3 1056.1 1057.7 1087.8 20679.0 21135.0 21279.0 21470.4 21526.6 1058.1 1082.6 1085.9 1095.1 1108.6 10922.2 11711.5 11797.3 11840.2 11991.3 472944.0 480452.0 481664.0 508600.0 509323.0 61102.7 94601.0 95033.0 95341.0 99539.5 1056.4 1087.3 1098.5 1103.2 1112.6 16048.4 18392.6 18591.7 18750.8 19071.0 42686.4 43802.6 45531.8 45690.8 46489.1 64674.7 64840.4 65010.7 66185.0 66736.3 9191.7 9250.7 9443.0 9516.7 9618.8 13309.1 13862.7 14053.9 14067.4 14248.3 12233.8 12446.1 12576.9 12583.1 12784.7 12143.5 12293.6 12349.0 12398.7 12636.9 12505.1 12570.7 12631.4 12765.2 12947.9 9103.9 9208.6 9269.1 9310.0 9331.2 11016.5 12386.1 12450.6 12491.0 12776.9 01:40:41 INFO - PROCESS | 12665 | 01:40:41 INFO - PROCESS | 12665 | [#3] mootools.html Cycles:180 Average:6441.22 Median:4520.25 stddev:3715.06 (82.2%) stddev-sans-first:3707.15 01:40:41 INFO - PROCESS | 12665 | Values: 1530.1 1571.7 1754.5 1852.9 1869.3 8826.4 9366.9 9418.7 10141.6 10157.8 9508.3 9743.9 9752.0 9765.8 9850.3 2543.1 2583.1 2595.6 2601.0 2608.8 2508.9 2600.4 2621.0 2624.0 2624.7 2503.7 2542.2 2550.2 2553.0 2586.4 2506.1 2509.8 2510.7 2539.1 2569.8 2482.7 2503.6 2506.7 2567.7 2573.0 9974.3 10059.6 10064.9 10069.8 10082.0 5854.2 6001.1 6034.8 6038.1 6044.0 3703.9 3724.4 3781.6 3811.3 3943.9 1167.7 1195.6 1204.1 1207.9 1273.1 13438.3 13607.4 13796.8 14120.5 14245.5 4977.8 4999.6 5149.9 5190.6 5206.0 10896.0 10921.6 10921.6 10927.4 10934.3 5769.4 5811.6 5813.8 5814.9 5831.7 4924.6 5360.3 5450.9 5493.9 5511.4 4238.2 4251.5 4276.0 4304.9 4358.1 1264.6 1265.2 1285.7 1298.2 1330.6 940.6 1094.7 1101.9 1103.7 1118.1 6465.5 6517.0 6543.2 6581.9 6629.0 481.9 482.1 484.8 488.4 492.5 4520.1 4520.4 4556.1 4585.7 4608.2 11929.2 12189.0 12316.0 12322.9 12332.3 11741.4 12250.6 12299.6 12506.5 12548.3 9138.8 9223.4 9234.4 9306.8 9364.6 6522.1 6645.6 6653.6 6678.6 6688.0 11357.5 11368.3 11417.5 11429.2 11512.7 11186.8 11386.1 11502.9 11637.8 11766.2 7705.9 8228.8 8420.9 8520.8 8557.2 9593.9 9615.2 9625.4 9626.7 9718.4 8353.3 8617.8 8629.0 8652.4 8655.0 8144.4 8255.9 8289.5 8316.7 8464.1 7835.1 7905.8 7908.1 7961.1 7997.8 5673.0 5856.2 5899.6 5920.0 5964.6 5830.2 5851.0 5896.7 5947.0 5958.2 01:40:41 INFO - PROCESS | 12665 | 01:40:41 INFO - PROCESS | 12665 | [#4] prototype.html Cycles:180 Average:5055.12 Median:5690.99 stddev:1511.66 (26.6%) stddev-sans-first:1474.04 01:40:41 INFO - PROCESS | 12665 | Values: 347.9 351.0 354.3 354.4 363.4 5931.3 5939.8 5950.4 5966.8 6002.0 5790.7 5809.7 5817.5 5883.7 5890.4 4302.2 4479.2 4496.0 4507.3 4542.6 4499.4 4525.9 4545.0 4551.8 4558.9 4523.7 4537.3 4551.7 4559.5 4568.2 4397.7 4453.3 4481.1 4490.7 4495.2 3939.2 3952.3 3952.7 3963.8 3972.7 5675.2 5706.8 5720.4 5738.2 5742.9 5052.7 5110.2 5134.4 5159.5 5200.2 6001.0 6001.5 6094.6 6099.4 6121.5 4262.7 4356.9 4357.9 4361.4 4365.3 6021.8 6141.8 6354.5 6372.3 6378.3 4320.3 4342.8 4350.4 4364.6 4368.1 4413.0 4427.3 4433.6 4436.0 4442.4 4968.5 5065.0 5096.5 5125.1 5144.3 6019.7 6050.0 6064.8 6122.1 6153.1 1523.8 1541.9 1547.8 1549.0 1562.2 943.8 987.7 996.5 1003.8 1015.5 5229.5 5243.3 5253.9 5258.5 5276.9 5446.8 5566.5 5579.7 5582.7 5618.9 4879.6 5208.1 5226.6 5273.0 5295.2 2961.3 2966.9 2975.0 2978.5 2987.6 5603.4 5610.0 5618.7 5709.2 5728.5 5940.6 6400.7 6411.7 6472.9 6507.6 6539.0 6542.4 6551.9 6553.3 6556.3 6105.6 6289.3 6341.2 6351.4 6358.8 6194.5 6275.7 6275.9 6279.4 6281.4 6181.6 6412.9 6492.4 6499.2 6521.4 4431.8 4446.5 4466.4 4473.6 4475.2 6281.5 6317.5 6373.9 6425.6 6452.9 6143.5 6158.9 6205.1 6208.6 6210.0 5945.6 6120.4 6151.3 6197.3 6237.1 6012.8 6070.8 6080.3 6117.7 6145.4 6140.4 6179.6 6213.5 6225.5 6227.3 5721.8 5993.3 6034.7 6049.2 6059.5 01:40:41 INFO - PROCESS | 12665 | 01:40:41 INFO - PROCESS | 12665 | [#5] yui.html Cycles:180 Average:23533.17 Median:24411.19 stddev:34410.95 (141.0%) stddev-sans-first:34465.30 01:40:41 INFO - PROCESS | 12665 | Values: 841.5 852.8 853.2 892.8 949.8 17577.5 18186.5 18275.5 18441.2 18543.9 15544.9 16000.6 18178.7 18387.9 18395.4 883.7 890.1 914.0 916.0 916.1 905.5 906.9 915.3 920.3 921.5 962.3 967.8 970.4 972.2 975.7 961.9 966.1 969.3 972.3 973.0 704.4 709.3 712.3 712.8 717.0 16807.6 17371.1 17539.9 17588.9 17611.2 15844.7 16232.5 16310.4 16383.3 16692.2 13179.3 13300.8 13359.5 13401.3 13694.2 4177.4 4211.1 4218.2 4253.9 4347.6 78549.2 89185.0 89308.0 89642.6 89661.1 12150.5 13048.3 13053.4 13495.7 14041.2 23972.6 24361.5 24383.1 24387.0 24435.4 9102.1 9219.9 9364.1 9584.0 9617.0 4809.8 5011.4 5049.4 5160.9 5228.4 4126.1 4297.3 4315.0 4338.3 4350.2 728.1 728.5 734.0 738.9 745.0 640.7 685.0 754.5 844.4 866.7 10227.2 11219.6 11309.9 11334.7 11711.6 815.7 822.5 828.1 831.5 844.7 5197.9 5266.4 5339.3 5353.1 5383.6 75302.0 78771.6 138366.0 138719.0 143475.0 118108.0 118301.0 119945.4 123185.4 123968.1 104267.0 104979.0 106558.5 107779.9 107890.5 9949.4 10070.4 10143.9 10258.7 10259.9 57976.7 59598.4 59708.4 61012.4 61450.1 70445.6 86417.6 86673.0 87961.0 90060.1 13328.6 13332.9 13538.9 13555.6 13851.4 14078.2 14377.9 14533.4 14830.8 14921.8 14169.4 14378.3 14697.6 14699.0 14926.8 14014.2 14893.3 15087.7 15500.8 15533.5 13584.5 13603.3 14644.9 14770.6 14891.3 11733.4 11875.5 11944.2 12153.8 12608.7 12216.1 12296.9 12404.1 12516.9 12889.9 01:40:41 INFO - PROCESS | 12665 | -------- Summary: end -------- 01:40:41 INFO - PROCESS | 12665 | 01:40:41 INFO - TEST-INFO | 12665: exit 0 01:40:41 INFO - TEST-OK | dromaeo_css | took 1111214ms 01:40:41 INFO - TEST-START | kraken 01:40:41 INFO - operating with platform_type : linux_ 01:40:41 INFO - Initialising browser for kraken test... 01:40:41 INFO - TEST-INFO | started process 12932 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUEqZdu/profile http://localhost:56889/getInfo.html) 01:40:46 INFO - PROCESS | 12932 | __metrics Screen width/height:1600/1200 01:40:46 INFO - PROCESS | 12932 | colorDepth:24 01:40:46 INFO - PROCESS | 12932 | Browser inner width/height: 1024/697 01:40:46 INFO - PROCESS | 12932 | __metrics 01:40:46 ERROR - PROCESS | 12932 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 01:40:47 INFO - TEST-INFO | 12932: exit 0 01:40:47 INFO - Browser initialized. 01:40:47 INFO - Running cycle 1/1 for kraken test... 01:40:47 INFO - TEST-INFO | started process 13058 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUEqZdu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:40:59 INFO - PROCESS | 13058 | RSS: Main: 152678400 01:40:59 INFO - PROCESS | 13058 | 01:41:01 INFO - PROCESS | 13058 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56889/tests/kraken/driver.html?testName=audio-beat-detection) 01:41:02 INFO - PROCESS | 13058 | RSS: Main: 158007296 01:41:02 INFO - PROCESS | 13058 | 01:41:05 INFO - PROCESS | 13058 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56889/tests/kraken/driver.html?testName=audio-dft) 01:41:05 INFO - PROCESS | 13058 | RSS: Main: 156348416 01:41:05 INFO - PROCESS | 13058 | 01:41:08 INFO - PROCESS | 13058 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56889/tests/kraken/driver.html?testName=audio-fft) 01:41:09 INFO - PROCESS | 13058 | RSS: Main: 156737536 01:41:09 INFO - PROCESS | 13058 | 01:41:11 INFO - PROCESS | 13058 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56889/tests/kraken/driver.html?testName=audio-oscillator) 01:41:12 INFO - PROCESS | 13058 | RSS: Main: 156258304 01:41:12 INFO - PROCESS | 13058 | 01:41:14 INFO - PROCESS | 13058 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56889/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:41:14 INFO - PROCESS | 13058 | RSS: Main: 157077504 01:41:14 INFO - PROCESS | 13058 | 01:41:18 INFO - PROCESS | 13058 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56889/tests/kraken/driver.html?testName=imaging-darkroom) 01:41:18 INFO - PROCESS | 13058 | RSS: Main: 155967488 01:41:18 INFO - PROCESS | 13058 | 01:41:22 INFO - PROCESS | 13058 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56889/tests/kraken/driver.html?testName=imaging-desaturate) 01:41:22 INFO - PROCESS | 13058 | RSS: Main: 155164672 01:41:22 INFO - PROCESS | 13058 | 01:41:25 INFO - PROCESS | 13058 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56889/tests/kraken/driver.html?testName=json-parse-financial) 01:41:26 INFO - PROCESS | 13058 | RSS: Main: 156254208 01:41:26 INFO - PROCESS | 13058 | 01:41:27 INFO - PROCESS | 13058 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56889/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:41:28 INFO - PROCESS | 13058 | RSS: Main: 156020736 01:41:28 INFO - PROCESS | 13058 | 01:41:30 INFO - PROCESS | 13058 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56889/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:41:30 INFO - PROCESS | 13058 | RSS: Main: 156753920 01:41:30 INFO - PROCESS | 13058 | 01:41:32 INFO - PROCESS | 13058 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56889/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:41:33 INFO - PROCESS | 13058 | RSS: Main: 157147136 01:41:33 INFO - PROCESS | 13058 | 01:41:36 INFO - PROCESS | 13058 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56889/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:41:36 INFO - PROCESS | 13058 | RSS: Main: 157057024 01:41:36 INFO - PROCESS | 13058 | 01:41:39 INFO - PROCESS | 13058 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56889/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:41:39 INFO - PROCESS | 13058 | RSS: Main: 157093888 01:41:39 INFO - PROCESS | 13058 | 01:41:41 INFO - PROCESS | 13058 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56889/tests/kraken/driver.html?testName=ai-astar) 01:41:41 INFO - PROCESS | 13058 | RSS: Main: 157757440 01:41:41 INFO - PROCESS | 13058 | 01:41:41 INFO - PROCESS | 13058 | __start_tp_report 01:41:41 INFO - PROCESS | 13058 | _x_x_mozilla_page_load 01:41:41 INFO - PROCESS | 13058 | _x_x_mozilla_page_load_details 01:41:41 INFO - PROCESS | 13058 | |i|pagename|runs| 01:41:41 INFO - PROCESS | 13058 | |0;ai-astar;100;111;103;110;111;114;110;111;110;110 01:41:41 INFO - PROCESS | 13058 | |1;audio-beat-detection;142;115;143;166;115;140;142;119;187;115 01:41:41 INFO - PROCESS | 13058 | |2;audio-dft;162;161;161;161;162;160;161;161;160;162 01:41:41 INFO - PROCESS | 13058 | |3;audio-fft;74;74;75;173;76;75;74;75;181;81 01:41:41 INFO - PROCESS | 13058 | |4;audio-oscillator;85;83;145;83;83;83;144;82;84;144 01:41:41 INFO - PROCESS | 13058 | |5;imaging-gaussian-blur;117;118;117;117;117;117;117;118;117;118 01:41:41 INFO - PROCESS | 13058 | |6;imaging-darkroom;147;146;147;148;146;146;147;148;146;146 01:41:41 INFO - PROCESS | 13058 | |7;imaging-desaturate;95;96;96;95;95;95;95;96;96;95 01:41:41 INFO - PROCESS | 13058 | |8;json-parse-financial;51;51;51;51;54;52;52;52;53;51 01:41:41 INFO - PROCESS | 13058 | |9;json-stringify-tinderbox;64;61;60;60;60;62;61;60;60;60 01:41:41 INFO - PROCESS | 13058 | |10;stanford-crypto-aes;75;77;72;73;73;73;72;74;77;74 01:41:41 INFO - PROCESS | 13058 | |11;stanford-crypto-ccm;120;125;127;124;135;134;118;116;177;119 01:41:41 INFO - PROCESS | 13058 | |12;stanford-crypto-pbkdf2;155;155;154;154;158;153;158;153;152;156 01:41:41 INFO - PROCESS | 13058 | |13;stanford-crypto-sha256-iterative;62;66;62;66;63;62;62;63;61;62 01:41:41 INFO - PROCESS | 13058 | __end_tp_report 01:41:41 INFO - PROCESS | 13058 | __start_cc_report 01:41:41 INFO - PROCESS | 13058 | _x_x_mozilla_cycle_collect,832 01:41:41 INFO - PROCESS | 13058 | __end_cc_report 01:41:41 INFO - PROCESS | 13058 | __startTimestamp1471509701875__endTimestamp 01:41:41 INFO - PROCESS | 13058 | 01:41:41 INFO - PROCESS | 13058 | ------- Summary: start ------- 01:41:41 INFO - PROCESS | 13058 | Number of tests: 14 01:41:41 INFO - PROCESS | 13058 | 01:41:41 INFO - PROCESS | 13058 | [#0] ai-astar Cycles:10 Average:109.00 Median:110.50 stddev:4.19 (3.8%) stddev-sans-first:2.92 01:41:41 INFO - PROCESS | 13058 | Values: 100.0 111.0 103.0 110.0 111.0 114.0 110.0 111.0 110.0 110.0 01:41:41 INFO - PROCESS | 13058 | 01:41:41 INFO - PROCESS | 13058 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:142.00 stddev:24.02 (16.9%) stddev-sans-first:25.44 01:41:41 INFO - PROCESS | 13058 | Values: 142.0 115.0 143.0 166.0 115.0 140.0 142.0 119.0 187.0 115.0 01:41:41 INFO - PROCESS | 13058 | 01:41:41 INFO - PROCESS | 13058 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 01:41:41 INFO - PROCESS | 13058 | Values: 162.0 161.0 161.0 161.0 162.0 160.0 161.0 161.0 160.0 162.0 01:41:41 INFO - PROCESS | 13058 | 01:41:41 INFO - PROCESS | 13058 | [#3] audio-fft Cycles:10 Average:95.80 Median:75.00 stddev:42.89 (57.2%) stddev-sans-first:44.76 01:41:41 INFO - PROCESS | 13058 | Values: 74.0 74.0 75.0 173.0 76.0 75.0 74.0 75.0 181.0 81.0 01:41:41 INFO - PROCESS | 13058 | 01:41:41 INFO - PROCESS | 13058 | [#4] audio-oscillator Cycles:10 Average:101.60 Median:83.00 stddev:29.50 (35.5%) stddev-sans-first:30.67 01:41:41 INFO - PROCESS | 13058 | Values: 85.0 83.0 145.0 83.0 83.0 83.0 144.0 82.0 84.0 144.0 01:41:41 INFO - PROCESS | 13058 | 01:41:41 INFO - PROCESS | 13058 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 01:41:41 INFO - PROCESS | 13058 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 118.0 01:41:41 INFO - PROCESS | 13058 | 01:41:41 INFO - PROCESS | 13058 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 01:41:41 INFO - PROCESS | 13058 | Values: 147.0 146.0 147.0 148.0 146.0 146.0 147.0 148.0 146.0 146.0 01:41:41 INFO - PROCESS | 13058 | 01:41:41 INFO - PROCESS | 13058 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 01:41:41 INFO - PROCESS | 13058 | Values: 95.0 96.0 96.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 01:41:41 INFO - PROCESS | 13058 | 01:41:41 INFO - PROCESS | 13058 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:1.03 (2.0%) stddev-sans-first:1.05 01:41:41 INFO - PROCESS | 13058 | Values: 51.0 51.0 51.0 51.0 54.0 52.0 52.0 52.0 53.0 51.0 01:41:41 INFO - PROCESS | 13058 | 01:41:41 INFO - PROCESS | 13058 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:60.50 stddev:1.32 (2.2%) stddev-sans-first:0.73 01:41:41 INFO - PROCESS | 13058 | Values: 64.0 61.0 60.0 60.0 60.0 62.0 61.0 60.0 60.0 60.0 01:41:41 INFO - PROCESS | 13058 | 01:41:41 INFO - PROCESS | 13058 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:74.00 stddev:1.83 (2.5%) stddev-sans-first:1.90 01:41:41 INFO - PROCESS | 13058 | Values: 75.0 77.0 72.0 73.0 73.0 73.0 72.0 74.0 77.0 74.0 01:41:41 INFO - PROCESS | 13058 | 01:41:41 INFO - PROCESS | 13058 | [#11] stanford-crypto-ccm Cycles:10 Average:129.50 Median:126.00 stddev:17.88 (14.2%) stddev-sans-first:18.64 01:41:41 INFO - PROCESS | 13058 | Values: 120.0 125.0 127.0 124.0 135.0 134.0 118.0 116.0 177.0 119.0 01:41:41 INFO - PROCESS | 13058 | 01:41:41 INFO - PROCESS | 13058 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.80 Median:155.00 stddev:2.04 (1.3%) stddev-sans-first:2.17 01:41:41 INFO - PROCESS | 13058 | Values: 155.0 155.0 154.0 154.0 158.0 153.0 158.0 153.0 152.0 156.0 01:41:41 INFO - PROCESS | 13058 | 01:41:41 INFO - PROCESS | 13058 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.90 Median:62.50 stddev:1.73 (2.8%) stddev-sans-first:1.80 01:41:41 INFO - PROCESS | 13058 | Values: 62.0 66.0 62.0 66.0 63.0 62.0 62.0 63.0 61.0 62.0 01:41:41 INFO - PROCESS | 13058 | -------- Summary: end -------- 01:41:41 INFO - PROCESS | 13058 | 01:41:42 INFO - TEST-INFO | 13058: exit 0 01:41:42 INFO - TEST-OK | kraken | took 61017ms 01:41:42 INFO - SUITE-END | took 1172s 01:41:43 INFO - Completed test suite (00:19:32) 01:41:43 INFO - javascript benchmark 01:41:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7861.387257127209, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3318.236805534728, 3324.484704870745, 3341.8997430077093, 3368.0, 3371.730261579074, 5839.065749480084, 5843.79546715865, 5869.794557190499, 5905.025670764318, 5912.7930522431725, 5762.654240745558, 5769.7692092316365, 5804.5936784425, 5807.941920580793, 5808.941910580893, 5219.086659834533, 5281.63028587999, 5285.207218917155, 5290.603204759639, 5310.548603368718, 5100.9489905100845, 5119.206522988943, 5150.8712282193055, 5243.947560524384, 5288.391834938992, 5286.603504737141, 5322.669332666834, 5328.17413300939, 5347.037533244014, 5379.327584051994, 5285.207218917174, 5293.23248129023, 5299.735013249341, 5306.6550674206055, 5322.600804939606, 4983.526564976321, 5114.28400023997, 5219.765110570033, 5239.056969745444, 5288.471152884719, 5706.77172913083, 5791.131330300446, 5814.418558144194, 5815.912761308584, 5817.301923769132, 6182.938170618281, 6219.160413344186, 6219.689015549205, 6232.376762323776, 6253.937460625381, 5973.1040343948325, 6145.539084568675, 6302.968485157545, 6308.085327627514, 6319.178506794087, 5773.191753154561, 5825.737841797087, 5830.941690583039, 5858.209141765849, 5862.853428664317, 6344.397282258178, 6370.58591191571, 6385.712642931079, 6390.45681117101, 6391.201099862517, 5929.199558059693, 6083.482903953125, 6102.572819902564, 6169.96915015431, 6182.28903676074, 6178.135061091451, 6303.086052522359, 6334.5249106317215, 6342.270638876588, 6342.714577844008, 6205.0, 6288.968555157195, 6301.747930082837, 6303.779367722108, 6304.0, 5972.462478376967, 6061.575689701678, 6290.025046117859, 6323.0, 6324.9051264231075, 4651.348811166439, 4695.201815691341, 4697.530246975275, 4717.386739723883, 4724.71651700899, 4191.580841915784, 4197.9370309445385, 4199.727017743837, 4210.59999300066, 4228.9788551057045, 5830.504407125357, 5847.8245652630485, 5877.147813567009, 5924.9703751480965, 5932.851678707981, 6195.070739389038, 6298.086777417248, 6315.842103947438, 6329.4303512683155, 6332.050192471166, 6022.277326720734, 6044.848878777978, 6057.454829065406, 6070.362611925772, 6077.17958075663, 4986.476419975923, 5110.054639891632, 5219.217117432416, 5229.816956406507, 5247.370315562081, 6276.560640755195, 6395.648239346788, 6415.941369673951, 6439.066335381343, 6453.741850326029, 6256.092866534418, 6266.436020758154, 6269.5611307208055, 6272.905906411499, 6274.278457977391, 6176.938230617637, 6272.905906411316, 6362.554621176473, 6429.7749578764515, 6435.1312572803945, 6025.698715064317, 6139.430809034469, 6151.384861513813, 6151.9384806151365, 6159.692015399301, 6244.0, 6254.8123556293385, 6264.248290205204, 6273.9372606273355, 6280.214973128359, 5911.911321330097, 6068.059450785136, 6123.234595675541, 6128.264608247039, 6140.232470941132, 6006.738584897046, 6113.867421735523, 6115.4408910389475, 6131.5295901671125, 6132.421572786907, 6011.458968692838, 6021.819345419644, 6055.303640081271, 6064.3935606439, 6074.210352654127, 5952.76188952437, 6050.1227322037175, 6124.356942521149, 6174.506039516918, 6201.627902325875, 5897.970510147422, 5988.730507127103, 6010.038393856962, 6014.1580178775885, 6042.0936859469675], "value": 5727.325628854239, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.4789237578553, 631.7378288010475, 660.4221306356939, 663.5288944849157, 717.4117223876417, 41130.0, 46751.76624116879, 46961.765191173996, 46997.53002469979, 47038.0, 47619.285710714365, 48245.0, 48273.51726482743, 48376.0, 48647.0, 9189.264858811295, 9224.0, 9249.583768730423, 9317.720468385925, 9346.766330841749, 9150.72547823563, 9185.40294880834, 9216.723498295061, 9231.861522077174, 9266.166045055943, 9146.86279705805, 9243.722688319362, 9275.118863707938, 9296.7675808105, 9312.674056408026, 9258.861117083248, 9302.0, 9310.115539023782, 9311.627534898596, 9314.86027709585, 9185.632574697003, 9301.255899528021, 9308.44149351041, 9339.579718912597, 9345.112214339626, 44358.33462498065, 45076.774616126706, 45207.321890171675, 45207.54792452099, 45241.321380179324, 3875.418687196915, 3947.388154836005, 3967.147063381387, 3982.482277303961, 4021.577734337911, 34839.0, 34878.65121348779, 35633.64366356329, 35689.0, 35899.64100359015, 4850.102730994778, 4867.123917694814, 5016.372953380827, 5023.447420783711, 5097.362829646294, 388414.0, 391232.0, 394737.0, 396044.0, 396477.0, 61779.6911015442, 61829.38170618326, 61839.38160618426, 61977.38022619806, 62058.37941620616, 39407.80296098501, 40041.39937900934, 40337.0, 41905.0, 42162.0, 18997.0, 19069.61860762777, 19085.713714294296, 19105.426837194907, 19137.2345106197, 14662.120272783453, 14774.113553186844, 14809.481668141563, 14816.851831481763, 14884.404623815142, 21086.578268434547, 21126.57746845085, 21220.36338909835, 21387.0, 21416.78583214179, 7697.692092316357, 7721.343685786771, 7828.725994590162, 8024.79938001543, 8031.83936321282, 1053.5417093564324, 1060.6446840308583, 1065.6643157405392, 1068.8289873620183, 1219.4573414830493, 34383.484247736305, 34536.0, 34591.30817383638, 34972.65027349694, 35200.82399588037, 1161.7560312334335, 1166.9649910502699, 1198.5265820001225, 1243.657994051643, 1270.955516556916, 17546.912265438845, 17980.2807887683, 18028.45914622537, 18149.54626134331, 18428.539286517676, 667394.0, 674397.0, 680320.0, 682654.0, 690861.0, 66951.0, 67017.32982670209, 67130.3286967124, 67553.32446675569, 67569.0, 1462.1154201708025, 1476.5496523560416, 1479.7706355514697, 1487.3530014443752, 1516.7421538338508, 35211.0, 35577.110572234866, 35652.64347356493, 35810.64189358177, 35880.64119358773, 57154.0, 57675.0, 57773.71113144408, 57907.0, 58003.0, 542084.0, 546566.0, 549658.0, 551894.0, 552503.0, 31018.72681644081, 31230.21924451907, 32039.0, 32166.83916580402, 32315.19212019718, 52232.0, 52924.47075529196, 53110.203346949056, 53296.73351633217, 53923.73038134784, 44863.775681121384, 45027.0, 45045.774771125936, 46459.767701161276, 47940.7602961983, 47035.294470583656, 47766.761166193945, 47786.522134778206, 48125.6423304487, 48143.5185648139, 40147.0, 44485.0, 45114.323285150094, 45199.32201016921, 46095.07809843851, 38974.41538376983, 40474.797626011685, 41078.235253137136, 41242.38136427896, 41606.0, 31262.53106203454, 31424.0, 31672.841635791672, 31831.363372731954, 31876.203094922814], "value": 21938.995952953, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.6718241815339, 407.7553467919247, 422.6407553579457, 423.90674051708623, 438.24840398716196, 20652.48368790781, 20653.896730516346, 21346.35960921173, 21648.2423115191, 21838.45403864904, 17177.79755417121, 19742.0, 20204.393868183943, 20321.49196270094, 21394.0, 5285.101532739424, 5433.26650902129, 5435.728213589305, 5477.20580515825, 5489.039418101836, 5313.123334649778, 5414.07960646689, 5415.620906536544, 5436.21174929635, 5457.754401051942, 5300.655457395276, 5395.460453954613, 5430.5384042356245, 5443.156310771837, 5444.91832622511, 5356.785728570852, 5434.184872269152, 5439.673619582818, 5698.6580805151825, 5897.115432685132, 5819.53443724501, 5823.621464604829, 5882.441168089024, 5883.3234178069215, 5901.498372638331, 14480.927595362062, 14580.708385832224, 14890.702185956328, 14965.850341496554, 14970.625734356729, 2358.9646155307682, 2424.7211570669424, 2452.2520631207476, 2474.183519438581, 2506.7994560435122, 11232.0, 11260.887391126065, 11326.886731132749, 13941.790873136912, 15028.924855375762, 1894.355918987551, 1984.0674882805204, 2037.8573499855015, 2047.991431164181, 2092.633789086919, 79539.0, 80263.04628756442, 83445.0, 83906.58046709727, 84023.0, 11476.770464590662, 11816.645500634995, 11921.403929803475, 12344.876551234553, 12657.746845063048, 16809.487146156906, 16863.0, 16900.915495422443, 16918.30816918323, 16948.322067117424, 8956.552172391355, 8966.506842123616, 8990.595423205972, 9090.545472726337, 9258.629654813867, 8797.736067917973, 8842.602082906285, 8916.420432671855, 9010.549472526347, 9107.81784364309, 5015.724135172601, 5084.618653600995, 5157.690538567698, 5162.277281180638, 5227.503387178212, 2035.1452389996236, 2100.9684854727193, 2136.978630213709, 2138.7219661443914, 2168.273628334494, 918.8162367526521, 1005.3465247588979, 1056.144522936424, 1057.7302787789065, 1087.7987572298991, 20679.0, 21135.0, 21279.0, 21470.355889323346, 21526.56946861054, 1058.1059005140644, 1082.604849230041, 1085.918556108295, 1095.0965453500894, 1108.6009036746764, 10922.1808364373, 11711.472983715754, 11797.29216247028, 11840.171188016757, 11991.280523168638, 472944.0, 480452.0, 481664.0, 508600.0, 509323.0, 61102.69448652817, 94601.0, 95033.0, 95341.0, 99539.50230248948, 1056.3609016545033, 1087.287826473677, 1098.5441041967654, 1103.2222283290262, 1112.609238451935, 16048.358065677243, 18392.632147357246, 18591.72112418342, 18750.812491874905, 19071.0, 42686.35970460508, 43802.561974379845, 45531.77234113808, 45690.77154614206, 46489.07021859611, 64674.676626616565, 64840.35159648532, 65010.67494662497, 66185.0, 66736.33263667495, 9191.678291259774, 9250.676226332045, 9442.95278523603, 9516.714498565054, 9618.807623847344, 13309.134906231064, 13862.722745545234, 14053.929730351283, 14067.437302508195, 14248.28758562047, 12233.816492752794, 12446.128770986306, 12576.87423125757, 12583.119181657195, 12784.6803829905, 12143.453544590338, 12293.631191063925, 12349.024156134976, 12398.690032749253, 12636.87363126357, 12505.062120341192, 12570.748585028065, 12631.368431578569, 12765.234085954873, 12947.935260324015, 9103.863442048241, 9208.631654733736, 9269.073092690676, 9310.0, 9331.206847418045, 11016.504257308601, 12386.132970691964, 12450.626481205578, 12491.0, 12776.936115319364], "value": 9621.99058204108, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1530.0590137065703, 1571.7170909236336, 1754.5175076853864, 1852.9444116676489, 1869.345728994852, 8826.426282291646, 9366.906330936697, 9418.670346537872, 10141.594336226552, 10157.796844063114, 9508.334416590824, 9743.853842192373, 9751.951240243807, 9765.779277590302, 9850.310478266525, 2543.1226226951717, 2583.1217386088724, 2595.623634572986, 2600.9739902601013, 2608.8304260223026, 2508.9372765680823, 2600.388908606476, 2621.043319188492, 2623.973760262392, 2624.724403937588, 2503.7371076037025, 2542.211914306571, 2550.2349295211457, 2553.0298486575075, 2586.3921978335075, 2506.147909710698, 2509.8494090354548, 2510.698716154055, 2539.1240022192314, 2569.820112592119, 2482.7229350860002, 2503.6119401492797, 2506.724260331362, 2567.653366795496, 2572.957952029431, 9974.251931105196, 10059.647912323107, 10064.89935100647, 10069.798604027952, 10082.0, 5854.18041474194, 6001.099835024781, 6034.758609655609, 6038.064100064497, 6044.032954727222, 3703.925921481582, 3724.4413337999245, 3781.621837816224, 3811.3330167220624, 3943.9014024649327, 1167.7430965187646, 1195.6293548999752, 1204.090911361925, 1207.9033677305795, 1273.0642977411594, 13438.328083595781, 13607.387667554984, 13796.793048104286, 14120.505782297672, 14245.501407450689, 4977.800887964441, 4999.575036121971, 5149.871253218699, 5190.6107041972, 5206.01085793698, 10896.0, 10921.563137474412, 10921.563137474412, 10927.3989930553, 10934.289271197347, 5769.365369809401, 5811.593188476851, 5813.767449302065, 5814.941850581525, 5831.7375718091935, 4924.630652701062, 5360.3031605891765, 5450.863728406822, 5493.917591236134, 5511.3937466878615, 4238.23711731885, 4251.489821221473, 4275.978620106879, 4304.859212308678, 4358.128374325148, 1264.6016504800957, 1265.240855486719, 1285.7299967007034, 1298.214580178997, 1330.60081975407, 940.6331530703031, 1094.6934858239706, 1101.8787933327328, 1103.7185517692938, 1118.111101674171, 6465.4827613790785, 6516.967415162989, 6543.247526534509, 6581.934180658228, 6629.0, 481.8891654919388, 482.14900700264064, 484.791539637952, 488.3529323646183, 492.48043314303754, 4520.118576877543, 4520.389747384127, 4556.066006468666, 4585.701929374647, 4608.239640459354, 11929.224600400947, 12189.0, 12316.0, 12322.876771232173, 12332.260064396165, 11741.412929353499, 12250.571230006906, 12299.569515066929, 12506.49974001048, 12548.309842958543, 9138.81722365549, 9223.446593204295, 9234.399764015452, 9306.813863722688, 9364.578593963288, 6522.086907832812, 6645.634490103091, 6653.634050127096, 6678.632675202911, 6687.966560167168, 11357.54569817198, 11368.261063030877, 11417.486213120595, 11429.199956003, 11512.712182195512, 11186.776264474502, 11386.089112871116, 11502.88497115018, 11637.825432618349, 11766.235194712317, 7705.884411733717, 8228.794280143044, 8420.915790842013, 8520.786980325542, 8557.229849313342, 9593.90406095958, 9615.182709469495, 9625.422474651543, 9626.711198664052, 9718.368306060083, 8353.289970352345, 8617.82764344697, 8629.0, 8652.394332396672, 8654.956725216334, 8144.389170812331, 8255.917440825515, 8289.461185023196, 8316.708915187939, 8464.068952415106, 7835.098963619029, 7905.802354940943, 7908.130105688255, 7961.124276329715, 7997.8400431992195, 5672.971635141798, 5856.238688970407, 5899.646021238739, 5920.023196172756, 5964.582479226413, 5830.2420685310635, 5851.034579294369, 5896.734646940812, 5946.970265148647, 5958.1658567801405], "value": 5035.286815964962, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.90954351868515, 350.9736769742269, 354.30202501072876, 354.41344574728834, 363.3713675341657, 5931.288245410557, 5939.762409503625, 5950.375210602884, 5966.836466888958, 6002.0, 5790.739416726247, 5809.651420914737, 5817.534597232211, 5883.735231914563, 5890.352061273267, 4302.204092242937, 4479.171353299634, 4495.955040449603, 4507.256302710049, 4542.591166794988, 4499.370088187657, 4525.864224073283, 4544.954660428103, 4551.7951692173765, 4558.886027849314, 4523.710742438406, 4537.296719008543, 4551.726896386211, 4559.544045595447, 4568.154891345095, 4397.714148580332, 4453.28747400416, 4481.148581769469, 4490.685652004361, 4495.168393847116, 3939.172773717523, 3952.347862602667, 3952.7430717003303, 3963.7819919904396, 3972.7417717848534, 5675.177099320616, 5706.800261990852, 5720.370759216483, 5738.196652468658, 5742.913856292159, 5052.725825706341, 5110.182370820651, 5134.358205224347, 5159.471858789204, 5200.167973124319, 6000.989668461071, 6001.459868611846, 6094.573379863411, 6099.359567245419, 6121.540884433685, 4262.701610887239, 4356.89107772305, 4357.912841743179, 4361.386287073803, 4365.279728844736, 6021.849453763691, 6141.815745527686, 6354.523410744213, 6372.3309052549275, 6378.330275321117, 4320.287152619782, 4342.848000319974, 4350.434443522385, 4364.6290065344165, 4368.148211098869, 4413.0, 4427.335899615019, 4433.578810013044, 4436.0462500562535, 4442.355858400514, 4968.453470118284, 5064.974675126601, 5096.490350964874, 5125.1287281162295, 5144.254083157921, 6019.69901504923, 6049.969750151221, 6064.818055458343, 6122.112293717386, 6153.138560601519, 1523.8171419429739, 1541.884358673104, 1547.8452154784657, 1548.95353139406, 1562.1954693332943, 943.7593413679605, 987.7234374375187, 996.5266498413196, 1003.8243307421172, 1015.5480811039017, 5229.535729995608, 5243.344581927259, 5253.9492101579835, 5258.474152584788, 5276.91300589152, 5446.754896029609, 5566.471185237396, 5579.7489112990015, 5582.720863956786, 5618.949083584544, 4879.584605620241, 5208.140656791663, 5226.5818734501145, 5272.9736351318, 5295.232191332235, 2961.318896653782, 2966.896158634481, 2975.0, 2978.508546089871, 2987.581738556604, 5603.439656034364, 5610.018246806874, 5618.747156377973, 5709.229254050733, 5728.51307638847, 5940.6435613863305, 6400.743970241138, 6411.743530258737, 6472.870542589122, 6507.577007494498, 6538.972918395312, 6542.443892269211, 6551.901721474086, 6553.311902250195, 6556.311587283267, 6105.633661980295, 6289.27673317556, 6341.224457152005, 6351.428371446592, 6358.841028974312, 6194.535409844189, 6275.717592708248, 6275.905861412174, 6279.434850863444, 6281.371862813865, 6181.629102253879, 6412.871742565216, 6492.415682588589, 6499.155109835691, 6521.445677117498, 4431.784107946027, 4446.466424029008, 4466.352378905106, 4473.575010374009, 4475.194464996332, 6281.466075383643, 6317.494600432046, 6373.8725225496155, 6425.614463132227, 6452.903206452001, 6143.508519318533, 6158.907616385668, 6205.131281620486, 6208.596441231305, 6210.0, 5945.583809133492, 6120.3682416561305, 6151.2618485781995, 6197.25632924052, 6237.126802247598, 6012.849678758066, 6070.848228794315, 6080.331163572092, 6117.663528505975, 6145.385461453998, 6140.4078614011305, 6179.62922224668, 6213.502919766317, 6225.470834979077, 6227.346128656607, 5721.828345149652, 5993.280806303097, 6034.732706131762, 6049.183360246487, 6059.515238780975], "value": 4570.178729528655, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [841.5245386356708, 852.774014886055, 853.1937319233323, 892.8258989497048, 949.753064203307, 17577.47267581973, 18186.5453363666, 18275.543111422223, 18441.170147343368, 18543.907280463613, 15544.922275388635, 16000.599985000352, 18178.72731909019, 18387.90806045971, 18395.448136555926, 883.7392969074139, 890.1410139215656, 914.021996463784, 916.0198587511366, 916.097643820836, 905.5245995852198, 906.8503696890039, 915.3455279475174, 920.2591913509622, 921.5300196899562, 962.3311798300149, 967.8257913575553, 970.3644113105892, 972.2270794718181, 975.6536429567508, 961.8941916389192, 966.1497881863914, 969.3166317746008, 972.3485264872588, 972.9610815567404, 704.4188544450798, 709.3438569323355, 712.262807993724, 712.7790384980673, 716.989245161323, 16807.579810504714, 17371.131443427777, 17539.912300438416, 17588.91205543977, 17611.207495662726, 15844.653204477634, 16232.513024609281, 16310.429134980337, 16383.262753176135, 16692.165391730487, 13179.341032948314, 13300.800487992688, 13359.465621375133, 13401.329933503383, 13694.246816425093, 4177.373393990895, 4211.073563815956, 4218.198542276958, 4253.851115210984, 4347.630451411634, 78549.21450785476, 89185.0, 89308.0, 89642.55178724065, 89661.10338896593, 12150.513979440724, 13048.34758262083, 13053.412596433183, 13495.662608434672, 14041.227732474812, 23972.64041039386, 24361.512769744506, 24383.14658986927, 24387.0, 24435.38911527226, 9102.13529714676, 9219.861702074475, 9364.110409511086, 9584.041595840361, 9617.0, 4809.759512024385, 5011.4236862761245, 5049.394072711299, 5160.896782064413, 5228.37259528859, 4126.071633882412, 4297.290946993773, 4314.9784251078545, 4338.262495375794, 4350.173467041315, 728.0644371982083, 728.536147172224, 733.9633018349168, 738.933495985364, 744.9515781474187, 640.6668532363238, 685.0375222811957, 754.4643303254641, 844.4215712237014, 866.696656170348, 10227.232957528215, 11219.60731374398, 11309.886901131047, 11334.716632084099, 11711.590094346828, 815.6533473273835, 822.4777266435856, 828.0725587342095, 831.5177197225595, 844.6536919862949, 5197.948020519746, 5266.4470230626, 5339.3058902342445, 5353.1167357386375, 5383.596230282745, 75302.0, 78771.60614196892, 138366.0, 138719.0, 143475.0, 118108.0, 118301.0, 119945.40027299807, 123185.38407307905, 123968.1404778929, 104267.0, 104979.0, 106558.46720766346, 107779.92220077856, 107890.46054769676, 9949.403035817873, 10070.439081942312, 10143.949280253551, 10258.743531411774, 10259.948700256451, 57976.71011644915, 59598.404015959284, 59708.402915970284, 61012.38987610067, 61450.07824882541, 70445.64777176286, 86417.56791216004, 86673.0, 87961.0, 90060.09939900516, 13328.600141995756, 13332.866671333162, 13538.93230533841, 13555.59333220005, 13851.376688049264, 14078.158049862757, 14377.856221437652, 14533.418663253351, 14830.777538336717, 14921.776173357626, 14169.433222670978, 14378.28108594587, 14697.632559185678, 14699.044562103429, 14926.776098358316, 14014.229217392733, 14893.255337232878, 15087.698246035237, 15500.844991550392, 15533.533993980198, 13584.52454164139, 13603.319834008062, 14644.853551464348, 14770.556883293519, 14891.329890154753, 11733.413329333669, 11875.465604047666, 11944.16390852632, 12153.779248499332, 12608.747825043265, 12216.144869859025, 12296.938515307724, 12404.069694773108, 12516.937415312865, 12889.935550322189], "value": 8483.253312212035, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1499.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 111.0, 103.0, 110.0, 111.0, 114.0, 110.0, 111.0, 110.0, 110.0], "value": 109.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 115.0, 143.0, 166.0, 115.0, 140.0, 142.0, 119.0, 187.0, 115.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 161.0, 162.0, 160.0, 161.0, 161.0, 160.0, 162.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 173.0, 76.0, 75.0, 74.0, 75.0, 181.0, 81.0], "value": 95.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 145.0, 83.0, 83.0, 83.0, 144.0, 82.0, 84.0, 144.0], "value": 101.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 118.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 148.0, 146.0, 146.0, 147.0, 148.0, 146.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 51.0, 51.0, 54.0, 52.0, 52.0, 52.0, 53.0, 51.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 60.0, 60.0, 60.0, 62.0, 61.0, 60.0, 60.0, 60.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 72.0, 73.0, 73.0, 73.0, 72.0, 74.0, 77.0, 74.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 125.0, 127.0, 124.0, 135.0, 134.0, 118.0, 116.0, 177.0, 119.0], "value": 129.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 155.0, 154.0, 154.0, 158.0, 153.0, 158.0, 153.0, 152.0, 156.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 62.0, 66.0, 63.0, 62.0, 62.0, 63.0, 61.0, 62.0], "value": 62.9, "unit": "score"}]}]} 01:41:43 INFO - Return code: 0 01:41:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:41:43 ERROR - # TBPL SUCCESS # 01:41:43 INFO - Running post-action listener: _resource_record_post_action 01:41:43 INFO - [mozharness: 2016-08-18 08:41:43.199634Z] Finished run-tests step (success) 01:41:43 INFO - Running post-run listener: _resource_record_post_run 01:41:43 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 270336; Write bytes: 328089600; Read time: 40; Write time: 222348 01:41:43 INFO - TinderboxPrint: CPU usage
13.2% 01:41:43 INFO - TinderboxPrint: I/O read bytes / time
270,336 / 40 01:41:43 INFO - TinderboxPrint: I/O write bytes / time
328,089,600 / 222,348 01:41:43 INFO - TinderboxPrint: CPU idle
8,229.8 (86.8%) 01:41:43 INFO - TinderboxPrint: CPU user
1,199.9 (12.6%) 01:41:43 INFO - TinderboxPrint: Swap in / out
0 / 0 01:41:43 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 77033472; Read time: 0; Write time: 74684 01:41:43 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 270336; Write bytes: 248602624; Read time: 40; Write time: 147444 01:41:43 INFO - Running post-run listener: _upload_blobber_files 01:41:43 INFO - Blob upload gear active. 01:41:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:41:43 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 01:41:43 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'] 01:41:43 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 01:41:44 INFO - (blobuploader) - INFO - Open directory for files ... 01:41:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:41:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:41:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:41:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:41:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:41:44 INFO - (blobuploader) - INFO - Done attempting. 01:41:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:41:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:41:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:41:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:41:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:41:48 INFO - (blobuploader) - INFO - Done attempting. 01:41:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:41:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:41:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:41:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:41:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:41:49 INFO - (blobuploader) - INFO - Done attempting. 01:41:49 INFO - (blobuploader) - INFO - Iteration through files over. 01:41:49 INFO - Return code: 0 01:41:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:41:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:41:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba0648e8affc034e7a1e40ee35e6dec5d1e205034ae717e44e2c630bc8fcbcf0a7023c3562c4571b15101bf18ba1d78b808768cdf7dc28055604d78f1ac11ea6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c03d845323085b2b609b5427fa3627e70aa4e150e18b3582c222e17545ab6848a23a1802a576032dec07330ac452202bbc282f1cf5e7ddf6d408d49c4d155fa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee00e75a33d2196b4047993161aa7c7879bd7ef13daadbdaed0367a453533196161edc4ebc3eb4ab1d949c8da9b56f84c6897ca29a1ca709b2357d624988f440"} 01:41:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:41:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:41:49 INFO - Contents: 01:41:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba0648e8affc034e7a1e40ee35e6dec5d1e205034ae717e44e2c630bc8fcbcf0a7023c3562c4571b15101bf18ba1d78b808768cdf7dc28055604d78f1ac11ea6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c03d845323085b2b609b5427fa3627e70aa4e150e18b3582c222e17545ab6848a23a1802a576032dec07330ac452202bbc282f1cf5e7ddf6d408d49c4d155fa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee00e75a33d2196b4047993161aa7c7879bd7ef13daadbdaed0367a453533196161edc4ebc3eb4ab1d949c8da9b56f84c6897ca29a1ca709b2357d624988f440"} 01:41:49 INFO - Running post-run listener: copy_logs_to_upload_dir 01:41:49 INFO - Copying logs to upload dir... 01:41:49 INFO - mkdir: /builds/slave/test/build/upload/logs 01:41:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.049024 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-08-18 01:41:48.929929) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 01:41:48.933414) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba0648e8affc034e7a1e40ee35e6dec5d1e205034ae717e44e2c630bc8fcbcf0a7023c3562c4571b15101bf18ba1d78b808768cdf7dc28055604d78f1ac11ea6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c03d845323085b2b609b5427fa3627e70aa4e150e18b3582c222e17545ab6848a23a1802a576032dec07330ac452202bbc282f1cf5e7ddf6d408d49c4d155fa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee00e75a33d2196b4047993161aa7c7879bd7ef13daadbdaed0367a453533196161edc4ebc3eb4ab1d949c8da9b56f84c6897ca29a1ca709b2357d624988f440"} build_url:https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009791 build_url: 'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba0648e8affc034e7a1e40ee35e6dec5d1e205034ae717e44e2c630bc8fcbcf0a7023c3562c4571b15101bf18ba1d78b808768cdf7dc28055604d78f1ac11ea6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c03d845323085b2b609b5427fa3627e70aa4e150e18b3582c222e17545ab6848a23a1802a576032dec07330ac452202bbc282f1cf5e7ddf6d408d49c4d155fa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee00e75a33d2196b4047993161aa7c7879bd7ef13daadbdaed0367a453533196161edc4ebc3eb4ab1d949c8da9b56f84c6897ca29a1ca709b2357d624988f440"}' ========= master_lag: 0.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 01:41:49.001020) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 01:41:49.001396) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1471505483.339754-1211179541 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004413 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 01:41:49.050946) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 01:41:49.051264) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 01:41:49.051598) ========= ========= Total master_lag: 0.17 =========