builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-015 starttime: 1472589655.69 results: success (0) buildid: 20160830104523 builduid: e2d101512e3e41ecb533c6d8aadfa538 revision: 5ed3f227fe0bb039f7e0b7210d2d3d9517d82d53 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 13:40:55.690116) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 13:40:55.690630) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 13:40:55.691007) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 13:40:55.705840) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:40:55.706209) ========= 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=20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-30 13:40:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 21.7M=0.001s 2016-08-30 13:40:56 (21.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.152490 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:40:55.876668) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:40:55.877502) ========= 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=20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013994 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:40:55.929113) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:40:55.929544) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5ed3f227fe0bb039f7e0b7210d2d3d9517d82d53 --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 5ed3f227fe0bb039f7e0b7210d2d3d9517d82d53 --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=20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-30 13:40:56,152 truncating revision to first 12 chars 2016-08-30 13:40:56,152 Setting DEBUG logging. 2016-08-30 13:40:56,152 attempt 1/10 2016-08-30 13:40:56,152 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5ed3f227fe0b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 13:40:56,428 unpacking tar archive at: mozilla-aurora-5ed3f227fe0b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.608543 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:40:56.552404) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 13:40:56.552794) ========= script_repo_revision: 5ed3f227fe0bb039f7e0b7210d2d3d9517d82d53 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 13:40:56.553367) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 13:40:56.553678) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 13:40:56.563431) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-08-30 13:40:56.563738) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:40:56 INFO - MultiFileLogger online at 20160830 13:40:56 in /builds/slave/test 13:40:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:40:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:40:56 INFO - {'append_to_log': False, 13:40:56 INFO - 'base_work_dir': '/builds/slave/test', 13:40:56 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:40:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:40:56 INFO - 'branch': 'Mozilla-Aurora', 13:40:56 INFO - 'buildbot_json_path': 'buildprops.json', 13:40:56 INFO - 'config_files': ('talos/linux_config.py',), 13:40:56 INFO - 'default_actions': ('clobber', 13:40:56 INFO - 'read-buildbot-config', 13:40:56 INFO - 'download-and-extract', 13:40:56 INFO - 'populate-webroot', 13:40:56 INFO - 'create-virtualenv', 13:40:56 INFO - 'install', 13:40:56 INFO - 'run-tests'), 13:40:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:40:56 INFO - 'download_minidump_stackwalk': True, 13:40:56 INFO - 'download_symbols': 'ondemand', 13:40:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:40:56 INFO - 'tooltool.py': '/tools/tooltool.py', 13:40:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:40:56 INFO - '/tools/misc-python/virtualenv.py')}, 13:40:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:40:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:40:56 INFO - 'installer_path': 'installer.exe', 13:40:56 INFO - 'log_level': 'info', 13:40:56 INFO - 'log_name': 'talos', 13:40:56 INFO - 'log_to_console': True, 13:40:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:40:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:40:56 INFO - 'opt_config_files': (), 13:40:56 INFO - 'pip_index': False, 13:40:56 INFO - 'sps_profile': False, 13:40:56 INFO - 'sps_profile_interval': 0, 13:40:56 INFO - 'suite': 'dromaeojs', 13:40:56 INFO - 'system_bits': '32', 13:40:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:40:56 INFO - 'title': 'talos-linux64-ix-015', 13:40:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:40:56 INFO - 'use_talos_json': True, 13:40:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:40:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:40:56 INFO - 'work_dir': 'build'} 13:40:56 INFO - [mozharness: 2016-08-30 20:40:56.876955Z] Running clobber step. 13:40:56 INFO - Running pre-action listener: _resource_record_pre_action 13:40:56 INFO - Running main action method: clobber 13:40:56 INFO - rmtree: /builds/slave/test/build 13:40:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:40:57 INFO - Running post-action listener: _resource_record_post_action 13:40:57 INFO - [mozharness: 2016-08-30 20:40:57.872543Z] Finished clobber step (success) 13:40:57 INFO - [mozharness: 2016-08-30 20:40:57.872643Z] Running read-buildbot-config step. 13:40:57 INFO - Running pre-action listener: _resource_record_pre_action 13:40:57 INFO - Running main action method: read_buildbot_config 13:40:57 INFO - Using buildbot properties: 13:40:57 INFO - { 13:40:57 INFO - "project": "", 13:40:57 INFO - "product": "firefox", 13:40:57 INFO - "script_repo_revision": "production", 13:40:57 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 13:40:57 INFO - "repository": "", 13:40:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 13:40:57 INFO - "buildid": "20160830104523", 13:40:57 INFO - "pgo_build": "True", 13:40:57 INFO - "basedir": "/builds/slave/test", 13:40:57 INFO - "buildnumber": 433, 13:40:57 INFO - "slavename": "talos-linux64-ix-015", 13:40:57 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 13:40:57 INFO - "platform": "ubuntu64_hw", 13:40:57 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 13:40:57 INFO - "branch": "mozilla-aurora", 13:40:57 INFO - "repo_path": "releases/mozilla-aurora", 13:40:57 INFO - "revision": "5ed3f227fe0bb039f7e0b7210d2d3d9517d82d53", 13:40:57 INFO - "stage_platform": "linux64-pgo", 13:40:57 INFO - "builduid": "e2d101512e3e41ecb533c6d8aadfa538", 13:40:57 INFO - "slavebuilddir": "test" 13:40:57 INFO - } 13:40:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 13:40:57 INFO - Running post-action listener: _resource_record_post_action 13:40:57 INFO - [mozharness: 2016-08-30 20:40:57.875063Z] Finished read-buildbot-config step (success) 13:40:57 INFO - [mozharness: 2016-08-30 20:40:57.875152Z] Running download-and-extract step. 13:40:57 INFO - Running pre-action listener: _resource_record_pre_action 13:40:57 INFO - Running main action method: download_and_extract 13:40:57 INFO - mkdir: /builds/slave/test/build/tests 13:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:40:57 INFO - https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:40:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:40:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:40:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:40:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:40:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:40:57 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:40:57 INFO - retry: Failed, sleeping 30 seconds before retrying 13:41:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:41:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:41:27 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:41:27 INFO - retry: Failed, sleeping 60 seconds before retrying 13:42:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:42:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:42:27 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:42:27 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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! 13:42:27 INFO - Caught exception: HTTP Error 404: Not Found 13:42:27 INFO - Caught exception: HTTP Error 404: Not Found 13:42:27 INFO - Caught exception: HTTP Error 404: Not Found 13:42:27 INFO - trying https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:42:27 INFO - Downloading https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:42:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:42:28 INFO - Downloaded 1361 bytes. 13:42:28 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:42:28 INFO - Using the following test package requirements: 13:42:28 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 13:42:28 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:42:28 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 13:42:28 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:42:28 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 13:42:28 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:42:28 INFO - u'jsshell-linux-x86_64.zip'], 13:42:28 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:42:28 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 13:42:28 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 13:42:28 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:42:28 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 13:42:28 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:42:28 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 13:42:28 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:42:28 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 13:42:28 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:42:28 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 13:42:28 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:42: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')]} 13:42:28 INFO - https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:42:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:42:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:42:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:42:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:42:28 WARNING - URL Error: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:42:28 INFO - Running command: ['nslookup', u'queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com'] 13:42:28 INFO - Copy/paste: nslookup queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com 13:42:28 INFO - Server: 10.26.75.40 13:42:28 INFO - Address: 10.26.75.40#53 13:42:28 INFO - queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com canonical name = proxxy1.srv.releng.scl3.mozilla.com. 13:42:28 INFO - Name: proxxy1.srv.releng.scl3.mozilla.com 13:42:28 INFO - Address: 10.26.48.46 13:42:28 INFO - Return code: 0 13:42:28 INFO - retry: attempt #1 caught exception: 13:42:28 INFO - retry: Failed, sleeping 30 seconds before retrying 13:42:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:42:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:42:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:42:58 INFO - retry: Failed, sleeping 60 seconds before retrying 13:43:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:43:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:43:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:43:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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! 13:43:58 INFO - Caught exception: 13:43:58 INFO - Caught exception: HTTP Error 404: Not Found 13:43:58 INFO - Caught exception: HTTP Error 404: Not Found 13:43:58 INFO - trying https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:43:58 INFO - Downloading https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:43:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:43:59 INFO - Downloaded 21768163 bytes. 13:43:59 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'] 13:43:59 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 13:44:00 INFO - Return code: 0 13:44:00 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 13:44:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:44:00 INFO - https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:44:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:44:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:44:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:44:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:44:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:44:00 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:44:00 INFO - retry: Failed, sleeping 30 seconds before retrying 13:44:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:44:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:44:30 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:44:30 INFO - retry: Failed, sleeping 60 seconds before retrying 13:45:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:45:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:45:30 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:45:30 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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! 13:45:30 INFO - Caught exception: HTTP Error 404: Not Found 13:45:30 INFO - Caught exception: HTTP Error 404: Not Found 13:45:30 INFO - Caught exception: HTTP Error 404: Not Found 13:45:30 INFO - trying https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:45:30 INFO - Downloading https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:45:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:45:31 INFO - Downloaded 21768163 bytes. 13:45:31 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'] 13:45:31 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 13:45:33 INFO - Return code: 0 13:45:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:45:33 INFO - https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:45:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:45:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:45:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:45:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:45:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:45:33 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:45:33 INFO - retry: Failed, sleeping 30 seconds before retrying 13:46:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:46:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:46:03 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:46:03 INFO - retry: Failed, sleeping 60 seconds before retrying 13:47:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:47:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:47:03 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:47:03 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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! 13:47:03 INFO - Caught exception: HTTP Error 404: Not Found 13:47:03 INFO - Caught exception: HTTP Error 404: Not Found 13:47:03 INFO - Caught exception: HTTP Error 404: Not Found 13:47:03 INFO - trying https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:47:03 INFO - Downloading https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:47:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:47:03 INFO - Downloaded 14413740 bytes. 13:47:03 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'] 13:47:03 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 13:47:04 INFO - Return code: 0 13:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:04 INFO - https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:47:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:47:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:47:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:47:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:47:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:47:04 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:47:04 INFO - retry: Failed, sleeping 30 seconds before retrying 13:47:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:47:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:47:34 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:47:34 INFO - retry: Failed, sleeping 60 seconds before retrying 13:48:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:48:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:48:34 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:48:34 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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! 13:48:34 INFO - Caught exception: HTTP Error 404: Not Found 13:48:34 INFO - Caught exception: HTTP Error 404: Not Found 13:48:34 INFO - Caught exception: HTTP Error 404: Not Found 13:48:34 INFO - trying https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:48:34 INFO - Downloading https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:48:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/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 13:48:35 INFO - Downloaded 63910532 bytes. 13:48:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:48:35 INFO - mkdir: /builds/slave/test/properties 13:48:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:48:35 INFO - Writing to file /builds/slave/test/properties/build_url 13:48:35 INFO - Contents: 13:48:35 INFO - build_url:https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:48:36 INFO - Running post-action listener: _resource_record_post_action 13:48:36 INFO - Running post-action listener: set_extra_try_arguments 13:48:36 INFO - [mozharness: 2016-08-30 20:48:36.060909Z] Finished download-and-extract step (success) 13:48:36 INFO - [mozharness: 2016-08-30 20:48:36.061141Z] Running populate-webroot step. 13:48:36 INFO - Running pre-action listener: _resource_record_pre_action 13:48:36 INFO - Running main action method: populate_webroot 13:48:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:48:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:48:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:48:36 INFO - '--tppagecycles', 13:48:36 INFO - '7'], 13:48:36 INFO - 'tests': ['tsvgm']}}, 13:48:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:48:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:48:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:48:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:48:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:48:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:48:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:48:36 INFO - 'talos_options': ['--disable-e10s'], 13:48:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:48:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:48:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:48:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:48:36 INFO - 'talos_options': ['--disable-e10s'], 13:48:36 INFO - 'tests': ['damp', 'tps']}, 13:48:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:48:36 INFO - 'tests': ['damp', 'tps']}, 13:48:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:48:36 INFO - 'tests': ['dromaeo_dom']}, 13:48:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:48:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:48:36 INFO - 'tests': ['basic_compositor_video']}, 13:48:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:48:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:48:36 INFO - 'tests': ['a11yr', 13:48:36 INFO - 'ts_paint', 13:48:36 INFO - 'tpaint', 13:48:36 INFO - 'sessionrestore', 13:48:36 INFO - 'sessionrestore_no_auto_restore', 13:48:36 INFO - 'tabpaint']}, 13:48:36 INFO - 'other-e10s': {'tests': ['a11yr', 13:48:36 INFO - 'ts_paint', 13:48:36 INFO - 'tpaint', 13:48:36 INFO - 'sessionrestore', 13:48:36 INFO - 'sessionrestore_no_auto_restore', 13:48:36 INFO - 'tabpaint']}, 13:48:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:48:36 INFO - 'ts_paint', 13:48:36 INFO - 'tpaint', 13:48:36 INFO - 'sessionrestore', 13:48:36 INFO - 'sessionrestore_no_auto_restore', 13:48:36 INFO - 'tabpaint']}, 13:48:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:48:36 INFO - 'ts_paint', 13:48:36 INFO - 'tpaint', 13:48:36 INFO - 'sessionrestore', 13:48:36 INFO - 'sessionrestore_no_auto_restore', 13:48:36 INFO - 'tabpaint']}, 13:48:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:48:36 INFO - 'tests': ['a11yr', 13:48:36 INFO - 'ts_paint', 13:48:36 INFO - 'tpaint', 13:48:36 INFO - 'sessionrestore', 13:48:36 INFO - 'sessionrestore_no_auto_restore', 13:48:36 INFO - 'tabpaint']}, 13:48:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:48:36 INFO - 'tests': ['a11yr', 13:48:36 INFO - 'ts_paint', 13:48:36 INFO - 'tpaint', 13:48:36 INFO - 'sessionrestore', 13:48:36 INFO - 'sessionrestore_no_auto_restore', 13:48:36 INFO - 'tabpaint']}, 13:48:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:48:36 INFO - 'tests': ['tsvgx', 13:48:36 INFO - 'tsvgr_opacity', 13:48:36 INFO - 'tart', 13:48:36 INFO - 'tscrollx', 13:48:36 INFO - 'cart']}, 13:48:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:48:36 INFO - 'tsvgr_opacity', 13:48:36 INFO - 'tart', 13:48:36 INFO - 'tscrollx', 13:48:36 INFO - 'cart']}, 13:48:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:48:36 INFO - 'talos_options': ['--disable-e10s'], 13:48:36 INFO - 'tests': ['tp5o']}, 13:48:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:48:36 INFO - 'tests': ['tp5o']}, 13:48:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:48:36 INFO - 'talos_options': ['--disable-e10s', 13:48:36 INFO - '--xperf_path', 13:48:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:48:36 INFO - 'tests': ['tp5n']}, 13:48:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:48:36 INFO - 'talos_options': ['--xperf_path', 13:48:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:48:36 INFO - 'tests': ['tp5n']}}, 13:48:36 INFO - 'talos.zip': {'path': '', 13:48:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:48:36 INFO - Running post-action listener: _resource_record_post_action 13:48:36 INFO - [mozharness: 2016-08-30 20:48:36.077210Z] Finished populate-webroot step (success) 13:48:36 INFO - [mozharness: 2016-08-30 20:48:36.077299Z] Running create-virtualenv step. 13:48:36 INFO - Running pre-action listener: _resource_record_pre_action 13:48:36 INFO - Running main action method: create_virtualenv 13:48:36 INFO - Creating virtualenv /builds/slave/test/build/venv 13:48:36 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 13:48:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:48:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:48:36 INFO - Using real prefix '/usr' 13:48:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:48:36 INFO - Installing distribute.............................................................................................................................................................................................done. 13:48:38 INFO - Installing pip.................done. 13:48:38 INFO - Return code: 0 13:48:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2223030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e23b0>, '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 0x24e32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e3740>, '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': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', '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 13:48:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:48:38 INFO - Using env: {'DISPLAY': ':0', 13:48:38 INFO - 'HOME': '/home/cltbld', 13:48:38 INFO - 'LANG': 'en_US.UTF-8', 13:48:38 INFO - 'LANGUAGE': 'en_US:en', 13:48:38 INFO - 'LOGNAME': 'cltbld', 13:48:38 INFO - 'MAIL': '/var/mail/cltbld', 13:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:38 INFO - 'MOZ_NO_REMOTE': '1', 13:48:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:48:38 INFO - 'NO_EM_RESTART': '1', 13:48:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:38 INFO - 'PWD': '/builds/slave/test', 13:48:38 INFO - 'SHELL': '/bin/bash', 13:48:38 INFO - 'SHLVL': '1', 13:48:38 INFO - 'TERM': 'linux', 13:48:38 INFO - 'TMOUT': '86400', 13:48:38 INFO - 'USER': 'cltbld', 13:48:38 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', 13:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:38 INFO - '_': '/tools/buildbot/bin/python'} 13:48:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:38 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 13:48:38 INFO - Cleaning up... 13:48:38 INFO - Return code: 0 13:48:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2223030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e23b0>, '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 0x24e32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e3740>, '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': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', '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 13:48:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:48:38 INFO - Using env: {'DISPLAY': ':0', 13:48:38 INFO - 'HOME': '/home/cltbld', 13:48:38 INFO - 'LANG': 'en_US.UTF-8', 13:48:38 INFO - 'LANGUAGE': 'en_US:en', 13:48:38 INFO - 'LOGNAME': 'cltbld', 13:48:38 INFO - 'MAIL': '/var/mail/cltbld', 13:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:38 INFO - 'MOZ_NO_REMOTE': '1', 13:48:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:48:38 INFO - 'NO_EM_RESTART': '1', 13:48:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:38 INFO - 'PWD': '/builds/slave/test', 13:48:38 INFO - 'SHELL': '/bin/bash', 13:48:38 INFO - 'SHLVL': '1', 13:48:38 INFO - 'TERM': 'linux', 13:48:38 INFO - 'TMOUT': '86400', 13:48:38 INFO - 'USER': 'cltbld', 13:48:38 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', 13:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:38 INFO - '_': '/tools/buildbot/bin/python'} 13:48:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:39 INFO - Downloading/unpacking psutil>=3.1.1 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:48:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:48:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:48:41 INFO - Installing collected packages: psutil 13:48:41 INFO - Running setup.py install for psutil 13:48:41 INFO - building 'psutil._psutil_linux' extension 13:48:41 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 13:48:41 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 13:48:41 INFO - building 'psutil._psutil_posix' extension 13:48:41 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 13:48:41 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 13:48:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:48:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:48:41 INFO - Successfully installed psutil 13:48:41 INFO - Cleaning up... 13:48:41 INFO - Return code: 0 13:48:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:48:41 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')]} 13:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:41 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')]} 13:48:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:41 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 0x2223030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e23b0>, '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 0x24e32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e3740>, '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': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', '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 13:48:41 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 13:48:41 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 13:48:41 INFO - Using env: {'DISPLAY': ':0', 13:48:41 INFO - 'HOME': '/home/cltbld', 13:48:41 INFO - 'LANG': 'en_US.UTF-8', 13:48:41 INFO - 'LANGUAGE': 'en_US:en', 13:48:41 INFO - 'LOGNAME': 'cltbld', 13:48:41 INFO - 'MAIL': '/var/mail/cltbld', 13:48:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:41 INFO - 'MOZ_NO_REMOTE': '1', 13:48:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:48:41 INFO - 'NO_EM_RESTART': '1', 13:48:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:48:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:41 INFO - 'PWD': '/builds/slave/test', 13:48:41 INFO - 'SHELL': '/bin/bash', 13:48:41 INFO - 'SHLVL': '1', 13:48:41 INFO - 'TERM': 'linux', 13:48:41 INFO - 'TMOUT': '86400', 13:48:41 INFO - 'USER': 'cltbld', 13:48:41 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', 13:48:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:41 INFO - '_': '/tools/buildbot/bin/python'} 13:48:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:48:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:48:43 INFO - Installing collected packages: mozsystemmonitor 13:48:43 INFO - Running setup.py install for mozsystemmonitor 13:48:43 INFO - Successfully installed mozsystemmonitor 13:48:43 INFO - Cleaning up... 13:48:43 INFO - Return code: 0 13:48:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:48:43 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')]} 13:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:43 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')]} 13:48:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:43 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 0x2223030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e23b0>, '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 0x24e32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e3740>, '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': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', '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 13:48:43 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 13:48:43 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 13:48:43 INFO - Using env: {'DISPLAY': ':0', 13:48:43 INFO - 'HOME': '/home/cltbld', 13:48:43 INFO - 'LANG': 'en_US.UTF-8', 13:48:43 INFO - 'LANGUAGE': 'en_US:en', 13:48:43 INFO - 'LOGNAME': 'cltbld', 13:48:43 INFO - 'MAIL': '/var/mail/cltbld', 13:48:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:43 INFO - 'MOZ_NO_REMOTE': '1', 13:48:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:48:43 INFO - 'NO_EM_RESTART': '1', 13:48:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:48:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:43 INFO - 'PWD': '/builds/slave/test', 13:48:43 INFO - 'SHELL': '/bin/bash', 13:48:43 INFO - 'SHLVL': '1', 13:48:43 INFO - 'TERM': 'linux', 13:48:43 INFO - 'TMOUT': '86400', 13:48:43 INFO - 'USER': 'cltbld', 13:48:43 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', 13:48:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:43 INFO - '_': '/tools/buildbot/bin/python'} 13:48:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:44 INFO - Downloading/unpacking jsonschema==2.5.1 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:48:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48:46 INFO - Downloading functools32-3.2.3-2.tar.gz 13:48:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:48:46 INFO - warning: no files found matching '*.txt' 13:48:46 INFO - no previously-included directories found matching 'build' 13:48:46 INFO - no previously-included directories found matching 'dist' 13:48:46 INFO - no previously-included directories found matching '.git*' 13:48:46 INFO - Installing collected packages: jsonschema, functools32 13:48:46 INFO - Running setup.py install for functools32 13:48:46 INFO - warning: no files found matching '*.txt' 13:48:46 INFO - no previously-included directories found matching 'build' 13:48:46 INFO - no previously-included directories found matching 'dist' 13:48:46 INFO - no previously-included directories found matching '.git*' 13:48:46 INFO - Successfully installed jsonschema functools32 13:48:46 INFO - Cleaning up... 13:48:46 INFO - Return code: 0 13:48:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2223030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e23b0>, '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 0x24e32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e3740>, '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': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', '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 13:48:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:48:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:48:46 INFO - Using env: {'DISPLAY': ':0', 13:48:46 INFO - 'HOME': '/home/cltbld', 13:48:46 INFO - 'LANG': 'en_US.UTF-8', 13:48:46 INFO - 'LANGUAGE': 'en_US:en', 13:48:46 INFO - 'LOGNAME': 'cltbld', 13:48:46 INFO - 'MAIL': '/var/mail/cltbld', 13:48:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:46 INFO - 'MOZ_NO_REMOTE': '1', 13:48:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:48:46 INFO - 'NO_EM_RESTART': '1', 13:48:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:48:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:46 INFO - 'PWD': '/builds/slave/test', 13:48:46 INFO - 'SHELL': '/bin/bash', 13:48:46 INFO - 'SHLVL': '1', 13:48:46 INFO - 'TERM': 'linux', 13:48:46 INFO - 'TMOUT': '86400', 13:48:46 INFO - 'USER': 'cltbld', 13:48:46 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', 13:48:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:46 INFO - '_': '/tools/buildbot/bin/python'} 13:48:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:48:46 INFO - Cleaning up... 13:48:46 INFO - Return code: 0 13:48:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2223030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e23b0>, '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 0x24e32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e3740>, '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': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', '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 13:48:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:48:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:48:46 INFO - Using env: {'DISPLAY': ':0', 13:48:46 INFO - 'HOME': '/home/cltbld', 13:48:46 INFO - 'LANG': 'en_US.UTF-8', 13:48:46 INFO - 'LANGUAGE': 'en_US:en', 13:48:46 INFO - 'LOGNAME': 'cltbld', 13:48:46 INFO - 'MAIL': '/var/mail/cltbld', 13:48:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:46 INFO - 'MOZ_NO_REMOTE': '1', 13:48:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:48:46 INFO - 'NO_EM_RESTART': '1', 13:48:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:48:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:46 INFO - 'PWD': '/builds/slave/test', 13:48:46 INFO - 'SHELL': '/bin/bash', 13:48:46 INFO - 'SHLVL': '1', 13:48:46 INFO - 'TERM': 'linux', 13:48:46 INFO - 'TMOUT': '86400', 13:48:46 INFO - 'USER': 'cltbld', 13:48:46 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', 13:48:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:46 INFO - '_': '/tools/buildbot/bin/python'} 13:48:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:46 INFO - Downloading/unpacking blobuploader==1.2.4 13:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:48 INFO - Downloading blobuploader-1.2.4.tar.gz 13:48:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:48:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:48:48 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 13:48:48 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 13:48:48 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 13:48:48 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 13:48:48 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 13:48:48 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 13:48:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:48:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:49 INFO - Downloading docopt-0.6.1.tar.gz 13:48:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:48:49 INFO - Installing collected packages: blobuploader, requests, docopt 13:48:49 INFO - Running setup.py install for blobuploader 13:48:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:48:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:48:49 INFO - Running setup.py install for requests 13:48:49 INFO - Running setup.py install for docopt 13:48:50 INFO - Successfully installed blobuploader requests docopt 13:48:50 INFO - Cleaning up... 13:48:50 INFO - Return code: 0 13:48:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:50 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 0x2223030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e23b0>, '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 0x24e32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e3740>, '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': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', '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 13:48:50 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 13:48:50 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 13:48:50 INFO - Using env: {'DISPLAY': ':0', 13:48:50 INFO - 'HOME': '/home/cltbld', 13:48:50 INFO - 'LANG': 'en_US.UTF-8', 13:48:50 INFO - 'LANGUAGE': 'en_US:en', 13:48:50 INFO - 'LOGNAME': 'cltbld', 13:48:50 INFO - 'MAIL': '/var/mail/cltbld', 13:48:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:50 INFO - 'MOZ_NO_REMOTE': '1', 13:48:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:48:50 INFO - 'NO_EM_RESTART': '1', 13:48:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:48:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:50 INFO - 'PWD': '/builds/slave/test', 13:48:50 INFO - 'SHELL': '/bin/bash', 13:48:50 INFO - 'SHLVL': '1', 13:48:50 INFO - 'TERM': 'linux', 13:48:50 INFO - 'TMOUT': '86400', 13:48:50 INFO - 'USER': 'cltbld', 13:48:50 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', 13:48:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:50 INFO - '_': '/tools/buildbot/bin/python'} 13:48:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:48:50 INFO - Running setup.py (path:/tmp/pip-HsCyY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:48:50 INFO - Running setup.py (path:/tmp/pip-DJrfJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:48:50 INFO - Running setup.py (path:/tmp/pip-lKGlSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:48:50 INFO - Running setup.py (path:/tmp/pip-vOCnKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:48:50 INFO - Running setup.py (path:/tmp/pip-gMp4VD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:48:50 INFO - Running setup.py (path:/tmp/pip-F4IsSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:48:50 INFO - Running setup.py (path:/tmp/pip-Aq07oW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:48:50 INFO - Running setup.py (path:/tmp/pip-MwBlAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:48:50 INFO - Running setup.py (path:/tmp/pip-4VInZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:48:50 INFO - Running setup.py (path:/tmp/pip-t8SmPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:48:51 INFO - Running setup.py (path:/tmp/pip-h7tvuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:48:51 INFO - Running setup.py (path:/tmp/pip-9AnmZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:48:51 INFO - Running setup.py (path:/tmp/pip-vejL02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:48:51 INFO - Running setup.py (path:/tmp/pip-M3w_1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:48:51 INFO - Running setup.py (path:/tmp/pip-YdfBL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:48:51 INFO - Running setup.py (path:/tmp/pip-Zcyxso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:48:51 INFO - Running setup.py (path:/tmp/pip-Ib4xVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:48:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:48:51 INFO - Running setup.py install for manifestparser 13:48:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:48:51 INFO - Running setup.py install for mozcrash 13:48:51 INFO - Running setup.py install for mozdebug 13:48:51 INFO - Running setup.py install for mozdevice 13:48:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:48:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:48:52 INFO - Running setup.py install for mozfile 13:48:52 INFO - Running setup.py install for mozhttpd 13:48:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:48:52 INFO - Running setup.py install for mozinfo 13:48:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:48:52 INFO - Running setup.py install for mozInstall 13:48:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:48:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:48:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:48:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:48:52 INFO - Running setup.py install for mozleak 13:48:52 INFO - Running setup.py install for mozlog 13:48:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:48:52 INFO - Running setup.py install for moznetwork 13:48:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:48:52 INFO - Running setup.py install for mozprocess 13:48:52 INFO - Running setup.py install for mozprofile 13:48:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:48:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:48:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:48:52 INFO - Running setup.py install for mozrunner 13:48:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:48:53 INFO - Running setup.py install for mozscreenshot 13:48:53 INFO - Running setup.py install for moztest 13:48:53 INFO - Running setup.py install for mozversion 13:48:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:48:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:48:53 INFO - Cleaning up... 13:48:53 INFO - Return code: 0 13:48:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:48: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')]} 13:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48: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')]} 13:48:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:53 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 0x2223030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e23b0>, '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 0x24e32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e3740>, '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': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', '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 13:48:53 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 13:48:53 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 13:48:53 INFO - Using env: {'DISPLAY': ':0', 13:48:53 INFO - 'HOME': '/home/cltbld', 13:48:53 INFO - 'LANG': 'en_US.UTF-8', 13:48:53 INFO - 'LANGUAGE': 'en_US:en', 13:48:53 INFO - 'LOGNAME': 'cltbld', 13:48:53 INFO - 'MAIL': '/var/mail/cltbld', 13:48:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:53 INFO - 'MOZ_NO_REMOTE': '1', 13:48:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:48:53 INFO - 'NO_EM_RESTART': '1', 13:48:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:48:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:53 INFO - 'PWD': '/builds/slave/test', 13:48:53 INFO - 'SHELL': '/bin/bash', 13:48:53 INFO - 'SHLVL': '1', 13:48:53 INFO - 'TERM': 'linux', 13:48:53 INFO - 'TMOUT': '86400', 13:48:53 INFO - 'USER': 'cltbld', 13:48:53 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', 13:48:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:53 INFO - '_': '/tools/buildbot/bin/python'} 13:48:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:48:53 INFO - Running setup.py (path:/tmp/pip-HJXe8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:48:53 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)) 13:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:48:53 INFO - Running setup.py (path:/tmp/pip-J3suHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:48:53 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)) 13:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:48:53 INFO - Running setup.py (path:/tmp/pip-1eQXY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:48:53 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)) 13:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:48:53 INFO - Running setup.py (path:/tmp/pip-dL16H9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:48:53 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)) 13:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:48:53 INFO - Running setup.py (path:/tmp/pip-Jzdab2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:48:53 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)) 13:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:48:53 INFO - Running setup.py (path:/tmp/pip-qz1VQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:48:54 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)) 13:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:48:54 INFO - Running setup.py (path:/tmp/pip-lGYjD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:48:54 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)) 13:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:48:54 INFO - Running setup.py (path:/tmp/pip-hbDqL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:48:54 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)) 13:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:48:54 INFO - Running setup.py (path:/tmp/pip-kJJyWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:48:54 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)) 13:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:48:54 INFO - Running setup.py (path:/tmp/pip-64DehR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:48:54 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)) 13:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:48:54 INFO - Running setup.py (path:/tmp/pip-s1wxBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:48:54 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)) 13:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:48:54 INFO - Running setup.py (path:/tmp/pip-NR_CCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:48:54 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)) 13:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:48:54 INFO - Running setup.py (path:/tmp/pip-57KEUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:48:54 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)) 13:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:48:54 INFO - Running setup.py (path:/tmp/pip-FORCIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:48:54 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)) 13:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:48:54 INFO - Running setup.py (path:/tmp/pip-bLdWZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:48:54 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)) 13:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:48:54 INFO - Running setup.py (path:/tmp/pip-J0OuqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:48:54 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)) 13:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:48:54 INFO - Running setup.py (path:/tmp/pip-FaC7iz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:48:54 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)) 13:48:54 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)) 13:48:54 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)) 13:48:54 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)) 13:48:54 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)) 13:48:54 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)) 13:48:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:56 INFO - Downloading blessings-1.6.tar.gz 13:48:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:48:56 INFO - Installing collected packages: blessings 13:48:56 INFO - Running setup.py install for blessings 13:48:56 INFO - Successfully installed blessings 13:48:56 INFO - Cleaning up... 13:48:56 INFO - Return code: 0 13:48:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:48:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:48:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:48:57 INFO - Reading from file tmpfile_stdout 13:48:57 INFO - Current package versions: 13:48:57 INFO - argparse == 1.2.1 13:48:57 INFO - blessings == 1.6 13:48:57 INFO - blobuploader == 1.2.4 13:48:57 INFO - docopt == 0.6.1 13:48:57 INFO - functools32 == 3.2.3-2 13:48:57 INFO - jsonschema == 2.5.1 13:48:57 INFO - manifestparser == 1.1 13:48:57 INFO - mozInstall == 1.12 13:48:57 INFO - mozcrash == 0.17 13:48:57 INFO - mozdebug == 0.1 13:48:57 INFO - mozdevice == 0.48 13:48:57 INFO - mozfile == 1.2 13:48:57 INFO - mozhttpd == 0.7 13:48:57 INFO - mozinfo == 0.9 13:48:57 INFO - mozleak == 0.1 13:48:57 INFO - mozlog == 3.3 13:48:57 INFO - moznetwork == 0.27 13:48:57 INFO - mozprocess == 0.23 13:48:57 INFO - mozprofile == 0.28 13:48:57 INFO - mozrunner == 6.12 13:48:57 INFO - mozscreenshot == 0.1 13:48:57 INFO - mozsystemmonitor == 0.3 13:48:57 INFO - moztest == 0.7 13:48:57 INFO - mozversion == 1.4 13:48:57 INFO - psutil == 3.1.1 13:48:57 INFO - requests == 1.2.3 13:48:57 INFO - wsgiref == 0.1.2 13:48:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:57 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 0x2223030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e23b0>, '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 0x24e32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e3740>, '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': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', '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 13:48:57 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 13:48:57 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 13:48:57 INFO - Using env: {'DISPLAY': ':0', 13:48:57 INFO - 'HOME': '/home/cltbld', 13:48:57 INFO - 'LANG': 'en_US.UTF-8', 13:48:57 INFO - 'LANGUAGE': 'en_US:en', 13:48:57 INFO - 'LOGNAME': 'cltbld', 13:48:57 INFO - 'MAIL': '/var/mail/cltbld', 13:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:57 INFO - 'MOZ_NO_REMOTE': '1', 13:48:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:48:57 INFO - 'NO_EM_RESTART': '1', 13:48:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:57 INFO - 'PWD': '/builds/slave/test', 13:48:57 INFO - 'SHELL': '/bin/bash', 13:48:57 INFO - 'SHLVL': '1', 13:48:57 INFO - 'TERM': 'linux', 13:48:57 INFO - 'TMOUT': '86400', 13:48:57 INFO - 'USER': 'cltbld', 13:48:57 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', 13:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:57 INFO - '_': '/tools/buildbot/bin/python'} 13:48:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:57 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)) 13:48:57 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)) 13:48:57 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)) 13:48:57 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)) 13:48:57 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)) 13:48:57 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)) 13:48:57 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)) 13:48:57 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)) 13:48:57 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)) 13:48:57 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)) 13:48:57 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)) 13:48:57 INFO - Cleaning up... 13:48:57 INFO - Return code: 0 13:48:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:57 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 0x2223030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e23b0>, '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 0x24e32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e3740>, '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': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', '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 13:48:57 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 13:48:57 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 13:48:57 INFO - Using env: {'DISPLAY': ':0', 13:48:57 INFO - 'HOME': '/home/cltbld', 13:48:57 INFO - 'LANG': 'en_US.UTF-8', 13:48:57 INFO - 'LANGUAGE': 'en_US:en', 13:48:57 INFO - 'LOGNAME': 'cltbld', 13:48:57 INFO - 'MAIL': '/var/mail/cltbld', 13:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:57 INFO - 'MOZ_NO_REMOTE': '1', 13:48:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:48:57 INFO - 'NO_EM_RESTART': '1', 13:48:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:57 INFO - 'PWD': '/builds/slave/test', 13:48:57 INFO - 'SHELL': '/bin/bash', 13:48:57 INFO - 'SHLVL': '1', 13:48:57 INFO - 'TERM': 'linux', 13:48:57 INFO - 'TMOUT': '86400', 13:48:57 INFO - 'USER': 'cltbld', 13:48:57 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', 13:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:57 INFO - '_': '/tools/buildbot/bin/python'} 13:48:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:48:57 INFO - Cleaning up... 13:48:57 INFO - Return code: 0 13:48:57 INFO - Running post-action listener: _resource_record_post_action 13:48:57 INFO - Running post-action listener: _start_resource_monitoring 13:48:57 INFO - Starting resource monitoring. 13:48:57 INFO - [mozharness: 2016-08-30 20:48:57.645470Z] Finished create-virtualenv step (success) 13:48:57 INFO - [mozharness: 2016-08-30 20:48:57.645913Z] Running install step. 13:48:57 INFO - Running pre-action listener: _resource_record_pre_action 13:48:57 INFO - Running main action method: install 13:48:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:48:57 INFO - Reading from file tmpfile_stdout 13:48:57 INFO - Detecting whether we're running mozinstall >=1.0... 13:48:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:48:57 INFO - Reading from file tmpfile_stdout 13:48:57 INFO - Output received: 13:48:57 INFO - Usage: mozinstall [options] installer 13:48:57 INFO - Options: 13:48:57 INFO - -h, --help show this help message and exit 13:48:57 INFO - -d DEST, --destination=DEST 13:48:57 INFO - Directory to install application into. [default: 13:48:57 INFO - "/builds/slave/test"] 13:48:57 INFO - --app=APP Application being installed. [default: firefox] 13:48:57 INFO - mkdir: /builds/slave/test/build/application 13:48:57 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'] 13:48:57 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 13:49:13 INFO - Reading from file tmpfile_stdout 13:49:13 INFO - Output received: 13:49:13 INFO - /builds/slave/test/build/application/firefox/firefox 13:49:13 INFO - Running post-action listener: _resource_record_post_action 13:49:13 INFO - [mozharness: 2016-08-30 20:49:13.980739Z] Finished install step (success) 13:49:13 INFO - [mozharness: 2016-08-30 20:49:13.981016Z] Running run-tests step. 13:49:13 INFO - Running pre-action listener: _resource_record_pre_action 13:49:13 INFO - Running main action method: run_tests 13:49:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:49:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:49:13 INFO - Python 2.7.3 13:49:13 INFO - Return code: 0 13:49:13 INFO - grabbing minidump binary from tooltool 13:49:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:49:13 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 0x24e23b0>, '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 0x24e32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e3740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:49:13 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 13:49:13 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 13:49:13 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 13:49:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:49:14 INFO - Return code: 0 13:49:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:49:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:49:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:49:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:49:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:49:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:49:14 INFO - Using env: {'DISPLAY': ':0', 13:49:14 INFO - 'HOME': '/home/cltbld', 13:49:14 INFO - 'LANG': 'en_US.UTF-8', 13:49:14 INFO - 'LANGUAGE': 'en_US:en', 13:49:14 INFO - 'LOGNAME': 'cltbld', 13:49:14 INFO - 'MAIL': '/var/mail/cltbld', 13:49:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:49:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:49:14 INFO - 'MOZ_NO_REMOTE': '1', 13:49:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:49:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:49:14 INFO - 'NO_EM_RESTART': '1', 13:49:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:49:14 INFO - 'PWD': '/builds/slave/test', 13:49:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:49:14 INFO - 'SHELL': '/bin/bash', 13:49:14 INFO - 'SHLVL': '1', 13:49:14 INFO - 'TERM': 'linux', 13:49:14 INFO - 'TMOUT': '86400', 13:49:14 INFO - 'USER': 'cltbld', 13:49:14 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430', 13:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:49:14 INFO - '_': '/tools/buildbot/bin/python'} 13:49:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:49:14 INFO - mozversion application_buildid: 20160830104523 13:49:14 INFO - mozversion application_changeset: 5ed3f227fe0bb039f7e0b7210d2d3d9517d82d53 13:49:14 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:49:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:49:14 INFO - mozversion application_name: Firefox 13:49:14 INFO - mozversion application_remotingname: firefox-dev 13:49:14 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:49:14 INFO - mozversion application_vendor: Mozilla 13:49:14 INFO - mozversion application_version: 50.0a2 13:49:14 INFO - mozversion platform_buildid: 20160830104523 13:49:14 INFO - mozversion platform_changeset: 5ed3f227fe0bb039f7e0b7210d2d3d9517d82d53 13:49:14 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:49:14 INFO - mozversion platform_version: 50.0a2 13:49:14 INFO - using testdate: 1472590154 13:49:14 INFO - actual date: 1472590154 13:49:14 INFO - starting webserver on 'localhost:49654' 13:49:14 INFO - SUITE-START | Running 2 tests 13:49:14 INFO - TEST-START | dromaeo_css 13:49:14 INFO - operating with platform_type : linux_ 13:49:14 INFO - Initialising browser for dromaeo_css test... 13:49:14 INFO - TEST-INFO | started process 19278 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8qkh7w/profile http://localhost:49654/getInfo.html) 13:49:18 INFO - PROCESS | 19278 | __metrics Screen width/height:1600/1200 13:49:18 INFO - PROCESS | 19278 | colorDepth:24 13:49:18 INFO - PROCESS | 19278 | Browser inner width/height: 1024/697 13:49:18 INFO - PROCESS | 19278 | __metrics 13:49:18 INFO - PROCESS | 19278 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:49:18 INFO - PROCESS | 19278 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:49:19 INFO - TEST-INFO | 19278: exit 0 13:49:19 INFO - Browser initialized. 13:49:19 INFO - Running cycle 1/1 for dromaeo_css test... 13:49:19 INFO - TEST-INFO | started process 19369 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8qkh7w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:49:31 INFO - PROCESS | 19369 | RSS: Main: 145346560 13:49:31 INFO - PROCESS | 19369 | 13:50:22 INFO - PROCESS | 19369 | 1472590222478 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] 13:52:31 INFO - PROCESS | 19369 | Cycle 1(1): loaded http://localhost:49654/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49654/tests/dromaeo/cssquery-ext.html) 13:52:32 INFO - PROCESS | 19369 | RSS: Main: 225013760 13:52:32 INFO - PROCESS | 19369 | 13:55:34 INFO - PROCESS | 19369 | Cycle 1(1): loaded http://localhost:49654/tests/dromaeo/cssquery-ext.html (next: http://localhost:49654/tests/dromaeo/cssquery-jquery.html) 13:55:35 INFO - PROCESS | 19369 | RSS: Main: 231174144 13:55:35 INFO - PROCESS | 19369 | 13:58:37 INFO - PROCESS | 19369 | Cycle 1(1): loaded http://localhost:49654/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49654/tests/dromaeo/cssquery-mootools.html) 13:58:37 INFO - PROCESS | 19369 | RSS: Main: 175071232 13:58:37 INFO - PROCESS | 19369 | 14:01:39 INFO - PROCESS | 19369 | Cycle 1(1): loaded http://localhost:49654/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49654/tests/dromaeo/cssquery-prototype.html) 14:01:40 INFO - PROCESS | 19369 | RSS: Main: 174788608 14:01:40 INFO - PROCESS | 19369 | 14:04:42 INFO - PROCESS | 19369 | Cycle 1(1): loaded http://localhost:49654/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49654/tests/dromaeo/cssquery-yui.html) 14:04:43 INFO - PROCESS | 19369 | RSS: Main: 176668672 14:04:43 INFO - PROCESS | 19369 | 14:07:45 INFO - PROCESS | 19369 | Cycle 1(1): loaded http://localhost:49654/tests/dromaeo/cssquery-yui.html (next: http://localhost:49654/tests/dromaeo/cssquery-dojo.html) 14:07:46 INFO - PROCESS | 19369 | RSS: Main: 262201344 14:07:46 INFO - PROCESS | 19369 | 14:07:46 INFO - PROCESS | 19369 | __start_tp_report 14:07:46 INFO - PROCESS | 19369 | _x_x_mozilla_page_load 14:07:46 INFO - PROCESS | 19369 | _x_x_mozilla_page_load_details 14:07:46 INFO - PROCESS | 19369 | |i|pagename|runs| 14:07:46 INFO - PROCESS | 19369 | |0;dojo.html;3226.322472280821;3264.4940034294677;3265.2489927316715;3294.028261662809;3308.0406682062203;5623.578231632629;5714.428557144294;5732.31212254529;5746.827595172141;5751.712414379274;5590.524805391547;5668.036433806253;5684.459976302255;5687.886242275152;5688.829335119953;4988.176950803131;5102.974485127569;5140.203268493371;5146.845594632157;5176.741162941857;5107.284980102789;5161.148410512261;5164.457731938149;5164.586833053366;5165.8450246492475;5118.769655365518;5141.948580514184;5157.200633901752;5176.870578235536;5177.534021938025;5183.222516622499;5184.196449550326;5200.453952334989;5210.192420174879;5236.816711415101;5055.266986286968;5081.441041485434;5085.186370180791;5121.820736274212;5126.5642420394315;5630.40880707528;5638.2670252866865;5676.148577713335;5682.318121825366;5735.397783232742;6164.383561643845;6172.074188871705;6176.289726681444;6182.28903676074;6182.412670796267;5983.58114931955;6006.729697163594;6009.849753756147;6097.786577469812;6221.626702397871;5673.716314184274;5688.146777983336;5692.943070569283;5699.629524080963;5700.686462244534;5997.2803263607775;6168.414000670019;6169.691515424211;6176.536759743038;6183.659898705614;5831.183634291203;5863.442972917566;5869.442402971711;5962.761489540369;5962.910556341659;6011.158437818709;6044.667543285073;6048.0625503047095;6050.183225264621;6051.273847138284;5893;5984.611000284967;5986.4013598639785;5988.700564971821;5991.850203744942;6006.609570377999;6044.758209671564;6059.424354686298;6113.202667687911;6113.628337950562;4683.578477937002;4691.437027556715;4694.88262793426;4718.646101542329;4721.055788842245;4137.275976704064;4183.142455796555;4193.119444916601;4194.979025104855;4203.726757760735;5784.768609255582;5805.419458054161;5823.155642431823;5881.647101173943;5917.378675239124;6058.606190597685;6100.450959413674;6101.145839582462;6107.603005804697;6129.969350153221;5674.375818659944;5729.054705973551;5802.0426629605645;5802.854928626818;5814.5348372130975;5179.171332586843;5226.242194881797;5226.477352264743;5267.78928842842;5293.100172970604;6253.593516421418;6258.998560230341;6265.780697675559;6266.561340706106;6267.874642507124;6032.125341825323;6065.6663883486835;6068.453839154321;6068.757249710139;6069.089636554376;6136.846578835565;6136.917362303904;6144.986077297196;6201.844953876189;6261.499080073674;5924;5933;5938.970305148447;5947.524198064231;5974.820755377345;5944.910826337522;5946.167536544801;6038.788642397495;6039.033754599243;6045.1234570987845;5918.970405147947;5948.553858460719;5949.8215053548465;6020.969895150496;6047.274327080779;5966.70166491665;5975;5978.910316345171;5982;5984.910226346521;5788.305403351625;5936.28764548257;5962.761489540543;5967.403259674172;5984.700764961647;5892.410758924245;5902.61632993854;5954.285485741739;6000.459958603747;6039.698015099315;5824.5340372770925;5829.8251052468495;5839;5844.2986841577585;5845.210896528914 14:07:46 INFO - PROCESS | 19369 | |1;ext.html;613.2977740487142;632.1434456311697;645.1806206118233;648.367841354679;659.7328082126737;34285.142871428194;37003.25993480129;45566.31650525245;46663.53336466634;46716.76641616787;46365.30452043222;46916;46974;46991.29513057298;47047.29429058567;8302;9238.491882946435;9370.250379969586;9380.24958003358;9381.15569598736;10818;11100.056495197918;11125.888741112567;11378.829317560201;11390.430478476042;11228.101751859831;11298.322100673986;11327.716807079807;11331.943340283287;11335.886641133608;11045.226834121575;11071.612493562767;11090.613673290838;11102.666919992373;11105.500252488657;10978.945105274503;11042.558297668083;11053.392063436508;11066.446677666085;11066.612668556643;44711;45126;45211.32183017257;45226.32160517595;45234.54765452336;3803.4675145479655;4006.346292009246;4021.77880216588;4317.740935543846;4652.395188625491;35123.297534049176;35283;35693.82153089244;36349.454758178385;36486.08784780375;4565.155446242456;4669.626429885635;4728.099387799857;4759.881002974918;4928.063667903123;360435;361276;362254.1887290573;362560;362956;36855.4471682925;59994;60334;60343.39656603466;60363;37624;39629.40555891664;39680.404793928115;39685.20629587392;39709.40435893464;16530.917345413196;16629.91685041567;16645.6670866582;16655.33378664864;16675.666486670198;13218.339083045808;13821.654458638615;13829.03196776216;14353.354099065567;14704.55886323412;9041.77395565116;9055.683051093312;9078.90921090794;9101.226395756436;9139.131782480654;3468.8439020243645;3483.7038851697384;3517.56030496188;3520.2431477232517;3573.5890372606964;973.7662960889336;1066.4081434803759;1118.9104871610252;1151.8732939376662;1202.4228370382266;34799.4780078299;35047;35188.296234075686;35547.82226088853;35770;981.8527220916777;1183.4260879045753;1189.5004098278744;1242.726600147966;1251.9687007824878;18242.25369008758;18312.175952082187;18397.080145992648;18406.079696015146;18548.350807721665;560689;560807;562351;563246;564850;62655.37344626495;63150.36849631445;63342.36657633457;63415;63449.36550634527;1301.5184381778802;1456.5120684570788;1467.5890750589851;1472.2417954753312;1474.5576327101826;31658;35115.82442087773;36351.27297453983;37100.44349334816;37278.627213727515;56416.153757692846;57004;57205.42794572001;57330.713346433;57390;477378;478039;480440;481613;593139;46488.53511464842;48277;48878.26682599693;49181.262281065094;49941.750291249766;52630;52751.47248527466;52845.20732188944;52920;53341.19988200103;44832.72341872075;45321;46269.768651156526;46715.29927051029;47777.522224777305;44539.55460445484;45173.77413112913;45387.54612453965;45659.315110272706;46901.2964805535;44946.550534494236;45806.54193458156;45984.770076149405;46158;46279.53720462887;37285.25429491341;39748.20503589854;39813.2037359257;40733.981650458976;40785.79607102064;29576.852115739282;29578.408431831675;30025.983833385813;30465.54301685432;30654.540181897737 14:07:46 INFO - PROCESS | 19369 | |2;jquery.html;396.4766508209165;417.5803317665747;422.674540603735;425.2175996167052;441.569469766977;15589.45436909707;19624.70562941557;20052;21482.677759833616;21534.461638459008;19354.709679354823;20114.195432182696;20446.897765511152;21015.159393624235;21211;5921.911171332423;5973.044312909935;6090.5736598438125;6097.725602347883;6100.389961003886;5851.941480585204;5866.120081987693;5926.259217597801;5927.436893495111;6047.123167140784;5914.290285165786;5943.673097979609;6012.068129439944;6019.21750172477;6029.728662210189;5861.2087368205175;5969.373215812342;5980.073088671263;5984.8503787405225;5991.880162396771;5346.144616861322;5355.705436201008;5398.8920221595345;5406.945930540723;5416.539594134503;24455.38861528476;24561.14036008749;24664.13675521366;24677.259682209562;25130.24609261725;2403.1468828565958;2564.2050964201158;2587.857667828269;2827.2790438438274;3037.1344166912545;15500.844991550053;15651.921740391339;15770.290336934866;15917.363305467767;15995.840041599551;2058.1561559760576;2069.0172168220124;2069.9482512937298;2132.445564153316;2210.7789221077924;83227;84367.5781621094;85428.57285713531;85836;86080.56959715224;10211.642592509375;10447.84328235077;11194.888051119384;11261.49323280438;11382.94308528452;15285.235738213045;16129.758053629204;16442.917785410995;16590.004599724056;16598.834011659972;8501.107383724744;8705.390622656354;8854.778630534289;8911.0930867871;9075.13786190311;8020.879686804703;8050.194980501903;8295.8340833183;8581.056083830774;8898.55507224649;7040.155181378267;7152.785416437411;7168.2831716827895;7192.172900116449;7217.639118044077;2015.2241387065792;2040.8061383934096;2060.484878780305;2094.0786054136133;2139.6362618354915;919.9494027828533;961.5624890674866;1040.8074506216374;1070.1866581398037;1103.9613613523488;22131.225407110673;22164.1134354624;22708.545829083327;23025.7697423027;23138.652920206212;987.1362557761959;1019.928604997643;1030.0832259289239;1075.3010543147018;1127.1602656021153;10895.097206257216;11527.71180720472;11590.246633968934;11610.361430121255;11638.767224655629;454093;461042;461131;481646;520411;60827;97040;97750;99504;100348;1006.5872992073362;1011.9342242754198;1027.121810851711;1032.0402026115635;1104.1498046504146;14294.857051429768;17959.640807184045;19234.422967311002;19289.807101929364;19491.51271218231;44201.77899110484;44341;45805.54194458013;45987.77006114948;46421;55746.442535574126;57920;59735.40264597299;60258.69870650794;60912.69543652253;8707.47755134694;12061;13245.602631921058;13433.865661343261;14236.501722439665;14316;14800.7779883304;14805.259737012891;14943.178125203218;15119.244037798286;12669.176503527242;12808;13052.608421747364;13167.60497185086;13172.473101076233;12892.355382231039;13155.671108222372;13264.867351326364;13466.259355735529;13523.932380338036;13039.47842086344;13419.261940593362;13491.25798081115;13493.595192144252;13794.172349659053;12802.039847011325;12831.294278814756;12863.935680321538;13133.802992955305;13434.529791457253;12826.294553799631;13188;13258.602241932758;13414.463421463422;13789.655258618615 14:07:46 INFO - PROCESS | 19369 | |3;mootools.html;1535.00224853845;1593.3148746039203;1730.8788384813067;1854.5120807645505;1860.4418674397677;9122.771930701736;9883.061109194632;9891.060349266818;9893.950530247339;9916.206703463724;8297.1702829717;9310.95344523278;9506.572204250808;9557.139857412849;9714.61141554337;2469.2839076667747;2498.612715029173;2499.825012249143;2500.1499490173337;2501.062101711858;2469.901203951849;2492.513959777844;2509.473010667762;2510.2845688978646;2519.407939134302;2468.049800826677;2470.2218801077693;2471.258622413278;2475.418276704982;2492.090387008747;2471.715752688437;2483.466054798218;2490.6139548370033;2508.987455062713;2511.4223728542356;2422.6729391532094;2449.669294645218;2462.8891699873548;2466.3957330453973;2506.0477018732763;9684.41893486393;9843.655472058515;9848.753781155458;9857.90142098577;9863.950680246624;5639.802606908738;5718.914216286759;5769.682667453289;5778.075507918755;5781.971090144564;3365;3390.593128824557;3464.636213197603;3491.9825400873087;3531.499112877017;1139.1399493382526;1175.435790820411;1193.653840386287;1209.2623499665197;1216.7444836584327;13373.866261337456;13425.261610611413;13426.597202083856;13440.260785656785;13457.05800593949;4849.636277279218;4963.553280204799;4986.7257300848805;5087.23691446286;5136.126858434075;11224.94387528057;11263;11264.042556382636;11275.774484510264;11277.605283815026;5766.596338256366;5775.047117225611;5777.335606405233;5786.016377215883;5787.479126878517;4644.465886423037;4774.283857421415;5228.6339956203465;5254.106801843695;5280.287161233185;4319.892002699895;4392.1874453226255;4426.22541055314;4451;4470.865874023784;1365.4060483689627;1386.105961654727;1393.470481217131;1394.2819447984295;1411.2590889782946;893.4907102951255;928.331601247101;985.9014098590084;993.9304248702522;1000.5547531348626;6058.848528786727;6340.7463701451425;6484.837879052967;6550.377714117151;6576.76981305652;478.9856304310876;482.0623886540645;486.7250003747871;496.42910652748907;497.4577710295795;4197.811098500575;4232.047789247456;4345.435093437833;4384.802683879169;4394.362817391524;11763.411829408666;11895.92936635707;11989.520419183309;12003.759924801456;12008.399580020963;11895.226810257478;11918.761624767456;11919.642410727693;11922.225055371546;11934;8522.446041007315;8756.605952732016;8776.91223087761;8923.910760892439;8976.820463590691;6293.811185664437;6356.618602883842;6385.936140638534;6533.248676402274;6572.4084832365315;10947.45262736844;11052.834207486734;11063.944680276547;11170.720731981766;11194.440277985905;10067.244956628429;10890.401027943539;11009;11111.388873611706;11244.775104498029;8908.955455222682;9194.678186263449;9228.123328283802;9286.582103805209;9388.765280868032;8815.382923195315;8957.059508751749;8962.32782541299;8969.641214351353;8970.461772293684;8387.622556984828;8436.072032076589;8625.913740862761;8630.697925572575;8671.089535598923;8267.710630127916;8279.172082791672;8314.16858314188;8480.06719260893;8603.784905377415;7305.634718263959;7451.701931922662;7531.962340188264;7669.61651917413;7754.147043825062;5626.606137570331;5751.626144300607;5799.971000144972;5805.216295800182;5982.790602328898;5702.914456283242;5835.716142448655;5863.325717542367;5900.64596124234;5938.524918006463 14:07:46 INFO - PROCESS | 19369 | |4;prototype.html;355.99288014239715;360.35316606690986;360.77271319068984;362.77145398398994;367.32962343722716;5510.614257002011;5541.667499950006;5594.216809646653;5598.972005139974;5644.294463192101;5463.453654634534;5538.418466061066;5551.333839939213;5553.583481238903;5560.443955604437;4144.46122004139;4267.637250833683;4282.464691913511;4294.184105020052;4298.935515967248;4234.983603935048;4263.957360426402;4270.210011147948;4271.145770845827;4316.784160791964;4234.491860976687;4251.957480425187;4258.233517966764;4273.337632666941;4297.720648157875;4198.937015944748;4240.978795106035;4267.103908179255;4271.444712187395;4297.247981603223;3697.759645623026;3701.2782507411066;3703.9444408333898;3712.6658600726064;3715.2755212733528;5355.892882142374;5385.919211211835;5390.245365648812;5423.674579525241;5453.963746888078;4758.214894542396;4891.779869905863;4900.730459824708;4906.877328066791;4968.950310496885;5695.601307908448;5696.6866822324755;5713.485786279213;5727.284089488814;5728.656280623176;4201.34879093741;4215.852445164435;4223.5142958559545;4227.62602154298;4232.026633874196;5893.646381217098;5941.821745347646;5982;6001.819945401645;6013.067974463965;4263.381809637585;4263.850765223202;4268.466441694788;4300.806463709078;4314.96440854199;4347.891302717458;4354.912901741947;4356.934645980313;4357.172137293904;4377.9124417511475;4712.5994290485005;4849.660523763303;4912.017596480719;5006.274090256965;5008.846196135697;5696.487316141567;5719.598698318939;5726.341470730836;5727.828165155052;5737.08206686928;1467.9339429725687;1497.5207933461188;1497.7304085264539;1499.932503037366;1500.2798656644873;912.3385545479474;954.7947191353892;967.9467629280316;977.5649835823133;977.5991843344194;4883.267509873547;4956.447429573773;4986.850394488171;5021.322121513622;5030.823921162815;5200.92198617021;5235.842924712265;5255.894882102337;5275.60432967529;5278.4721527846905;4735.602997115859;4943.8022479101155;4984.181937563456;4995.475475075137;5047.268146118792;2772.916812495628;2779.7359250871136;2794.720527947189;2796.517845541866;2808.227737372198;5323.22813192085;5326.946730532645;5341.332333458317;5363.973180134152;5415.48552887475;5006.524380183877;5240.423553409122;5256.763445644955;5313.654612450178;5715.628484148517;5936.883136353449;6023.879522409527;6060.878782424327;6095.542834287447;6122.938770612237;5914.526837852875;5957.195778570011;5980.9401905980385;5999.190109335358;6004;5582.134769110794;5862.765489380377;5901.380355062656;5918.165368735706;5940.346561878277;6133.601315914451;6168.352323006199;6178.196834411498;6191.761647670394;6196;4136.482939632546;4332.155229730172;4358.585934336233;4359.694821362475;4362.694611377173;5891.263592050994;5908.261467316585;5912.231409916683;5944.751602163594;5945.464908158287;5802.216700745345;5823.534117270693;5838.08095952024;5852.273579294033;5855.7410156814785;5669.234653321749;5741.196232527366;5761.740721667619;5770.163326317576;5823.242978412779;5698.943010569841;5701.030824759718;5709.771609135754;5744.167095771173;5759.2434307536605;5636.5208957237455;5842.152887831326;5886.146508756292;5886.793962211302;5889.734961926809;5649.915251271316;5668.659880407189;5678.381661226531;5683.2895888014;5684.403137670485 14:07:46 INFO - PROCESS | 19369 | |5;yui.html;777.5334799120528;799.3565180030077;855.7903313688146;859.0421679826993;925.3892430995536;17147.571310717238;18154.27382904684;18263.72604410935;18345.174467148994;18663.813361866378;16629.251683674232;17038.403655872014;17190.57023574411;17880.64238715225;18121.184546695396;840.6973489543759;844.1980118887051;844.6283635200492;849.7408290471403;877.7454538183935;828.4987582512583;831.1646794971045;831.4055450352996;831.4803247970019;834.1866679986995;876.1807709791366;876.7369789063288;876.7369789063288;876.8947726272856;878.995605021974;869.7303835810919;870.1342164546254;870.3472395703222;871.0418539606445;877.1228771228771;634.7238951056305;634.7334119669704;635.1267007864187;635.2281977397485;640.5356116815271;15091.39634414622;15503.767443488357;16771.2452939618;17015.659686806193;17045;16192.433264835674;16417.58956026097;16432.83567164337;16580.336786528525;16916.915415422965;13277.601671949857;13667.17996920188;13771.311434428238;13815.309234538334;14159.362828672734;4150.501939767248;4233.428487154226;4257.531671516131;4274.529801721808;4276.636485898703;89134.5543272286;89438;90062.99999999935;90811;93306.53346733224;12494;12513.499460021498;13031.739365212643;13131.146475479063;13329.866701332863;24780.25659230226;24794.628080578805;24935.501289974098;24944.750552494243;25615.48769024609;8327;8437.451565648213;8471.195236452528;8664.61009254585;9253.120953509406;4872.4883887192045;4944.629152813553;4980.352554167935;5074.213496907985;5164.819231326961;3747.4191500317493;3863.652271295597;3867.091233560111;3868.9806550967064;3871.380579107329;658.111549408295;660.600336796241;664.0106241699832;666.8199586111723;687.08274453605;742.8291492956542;755.2900273742774;772.3666593393476;784.0473824303499;808.5108509351758;9849.507524623883;10113.494325283706;10380.688579342632;10453.790924181474;10517.000884916073;648.2124219073847;693.7675878580732;720.5136532840312;732.1045265973064;740.8555331710264;4877.3903262092235;5164.671030105793;5369.24830523727;5380.354357477049;5404.486573775485;76516.61741691256;77567.61216193883;121182;132720.3363983174;135261;75620;76119;92453;93547.53226233825;116316.41841790907;92897;96798.5160074181;98900.50549747206;99409;100020;10205.132563732166;10439.634612788515;10443.538528324367;10494.790104198026;10506.684799456028;54189;54855;55779;56150.7192464035;56473.43526564682;80134;82393.17606823855;84906;85164.14835851562;86994.13005870114;13734.313284335543;13976.860231397963;14168.589280232844;14206.644833879236;14445.422183112558;15747;15772.05367677943;15828.841711582736;15994.840051599334;16770.916145419196;14774.261286935825;14877.404903803726;15190.848091519385;15521.76717349218;16866.409675661293;15028.398864045315;15115.168665723491;15288.77066843976;15710.764338534702;15752.842471575137;14298.714025719635;14532.927335363256;14565.635859103184;15185;15848;12208.145429819828;12305.876941230832;12535.185212961129;12784.424700888669;12876.22742635445;12666.050046246384;12786.232826030468;12889.742205156032;12933.353332333158;13124.96584886081 14:07:46 INFO - PROCESS | 19369 | __end_tp_report 14:07:46 INFO - PROCESS | 19369 | __start_cc_report 14:07:46 INFO - PROCESS | 19369 | _x_x_mozilla_cycle_collect,1073 14:07:46 INFO - PROCESS | 19369 | __end_cc_report 14:07:46 INFO - PROCESS | 19369 | __startTimestamp1472591266183__endTimestamp 14:07:46 INFO - PROCESS | 19369 | 14:07:46 INFO - PROCESS | 19369 | ------- Summary: start ------- 14:07:46 INFO - PROCESS | 19369 | Number of tests: 6 14:07:46 INFO - PROCESS | 19369 | 14:07:46 INFO - PROCESS | 19369 | [#0] dojo.html Cycles:165 Average:5637.42 Median:5881.65 stddev:632.22 (10.7%) stddev-sans-first:605.20 14:07:46 INFO - PROCESS | 19369 | Values: 3226.3 3264.5 3265.2 3294.0 3308.0 5623.6 5714.4 5732.3 5746.8 5751.7 5590.5 5668.0 5684.5 5687.9 5688.8 4988.2 5103.0 5140.2 5146.8 5176.7 5107.3 5161.1 5164.5 5164.6 5165.8 5118.8 5141.9 5157.2 5176.9 5177.5 5183.2 5184.2 5200.5 5210.2 5236.8 5055.3 5081.4 5085.2 5121.8 5126.6 5630.4 5638.3 5676.1 5682.3 5735.4 6164.4 6172.1 6176.3 6182.3 6182.4 5983.6 6006.7 6009.8 6097.8 6221.6 5673.7 5688.1 5692.9 5699.6 5700.7 5997.3 6168.4 6169.7 6176.5 6183.7 5831.2 5863.4 5869.4 5962.8 5962.9 6011.2 6044.7 6048.1 6050.2 6051.3 5893.0 5984.6 5986.4 5988.7 5991.9 6006.6 6044.8 6059.4 6113.2 6113.6 4683.6 4691.4 4694.9 4718.6 4721.1 4137.3 4183.1 4193.1 4195.0 4203.7 5784.8 5805.4 5823.2 5881.6 5917.4 6058.6 6100.5 6101.1 6107.6 6130.0 5674.4 5729.1 5802.0 5802.9 5814.5 5179.2 5226.2 5226.5 5267.8 5293.1 6253.6 6259.0 6265.8 6266.6 6267.9 6032.1 6065.7 6068.5 6068.8 6069.1 6136.8 6136.9 6145.0 6201.8 6261.5 5924.0 5933.0 5939.0 5947.5 5974.8 5944.9 5946.2 6038.8 6039.0 6045.1 5919.0 5948.6 5949.8 6021.0 6047.3 5966.7 5975.0 5978.9 5982.0 5984.9 5788.3 5936.3 5962.8 5967.4 5984.7 5892.4 5902.6 5954.3 6000.5 6039.7 5824.5 5829.8 5839.0 5844.3 5845.2 14:07:46 INFO - PROCESS | 19369 | 14:07:46 INFO - PROCESS | 19369 | [#1] ext.html Cycles:180 Average:64427.14 Median:4014.06 stddev:128298.34 (3196.2%) stddev-sans-first:128568.79 14:07:46 INFO - PROCESS | 19369 | Values: 613.3 632.1 645.2 648.4 659.7 34285.1 37003.3 45566.3 46663.5 46716.8 46365.3 46916.0 46974.0 46991.3 47047.3 8302.0 9238.5 9370.3 9380.2 9381.2 10818.0 11100.1 11125.9 11378.8 11390.4 11228.1 11298.3 11327.7 11331.9 11335.9 11045.2 11071.6 11090.6 11102.7 11105.5 10978.9 11042.6 11053.4 11066.4 11066.6 44711.0 45126.0 45211.3 45226.3 45234.5 3803.5 4006.3 4021.8 4317.7 4652.4 35123.3 35283.0 35693.8 36349.5 36486.1 4565.2 4669.6 4728.1 4759.9 4928.1 360435.0 361276.0 362254.2 362560.0 362956.0 36855.4 59994.0 60334.0 60343.4 60363.0 37624.0 39629.4 39680.4 39685.2 39709.4 16530.9 16629.9 16645.7 16655.3 16675.7 13218.3 13821.7 13829.0 14353.4 14704.6 9041.8 9055.7 9078.9 9101.2 9139.1 3468.8 3483.7 3517.6 3520.2 3573.6 973.8 1066.4 1118.9 1151.9 1202.4 34799.5 35047.0 35188.3 35547.8 35770.0 981.9 1183.4 1189.5 1242.7 1252.0 18242.3 18312.2 18397.1 18406.1 18548.4 560689.0 560807.0 562351.0 563246.0 564850.0 62655.4 63150.4 63342.4 63415.0 63449.4 1301.5 1456.5 1467.6 1472.2 1474.6 31658.0 35115.8 36351.3 37100.4 37278.6 56416.2 57004.0 57205.4 57330.7 57390.0 477378.0 478039.0 480440.0 481613.0 593139.0 46488.5 48277.0 48878.3 49181.3 49941.8 52630.0 52751.5 52845.2 52920.0 53341.2 44832.7 45321.0 46269.8 46715.3 47777.5 44539.6 45173.8 45387.5 45659.3 46901.3 44946.6 45806.5 45984.8 46158.0 46279.5 37285.3 39748.2 39813.2 40734.0 40785.8 29576.9 29578.4 30026.0 30465.5 30654.5 14:07:46 INFO - PROCESS | 19369 | 14:07:46 INFO - PROCESS | 19369 | [#2] jquery.html Cycles:180 Average:29803.94 Median:11872.62 stddev:78535.62 (661.5%) stddev-sans-first:78724.89 14:07:46 INFO - PROCESS | 19369 | Values: 396.5 417.6 422.7 425.2 441.6 15589.5 19624.7 20052.0 21482.7 21534.5 19354.7 20114.2 20446.9 21015.2 21211.0 5921.9 5973.0 6090.6 6097.7 6100.4 5851.9 5866.1 5926.3 5927.4 6047.1 5914.3 5943.7 6012.1 6019.2 6029.7 5861.2 5969.4 5980.1 5984.9 5991.9 5346.1 5355.7 5398.9 5406.9 5416.5 24455.4 24561.1 24664.1 24677.3 25130.2 2403.1 2564.2 2587.9 2827.3 3037.1 15500.8 15651.9 15770.3 15917.4 15995.8 2058.2 2069.0 2069.9 2132.4 2210.8 83227.0 84367.6 85428.6 85836.0 86080.6 10211.6 10447.8 11194.9 11261.5 11382.9 15285.2 16129.8 16442.9 16590.0 16598.8 8501.1 8705.4 8854.8 8911.1 9075.1 8020.9 8050.2 8295.8 8581.1 8898.6 7040.2 7152.8 7168.3 7192.2 7217.6 2015.2 2040.8 2060.5 2094.1 2139.6 919.9 961.6 1040.8 1070.2 1104.0 22131.2 22164.1 22708.5 23025.8 23138.7 987.1 1019.9 1030.1 1075.3 1127.2 10895.1 11527.7 11590.2 11610.4 11638.8 454093.0 461042.0 461131.0 481646.0 520411.0 60827.0 97040.0 97750.0 99504.0 100348.0 1006.6 1011.9 1027.1 1032.0 1104.1 14294.9 17959.6 19234.4 19289.8 19491.5 44201.8 44341.0 45805.5 45987.8 46421.0 55746.4 57920.0 59735.4 60258.7 60912.7 8707.5 12061.0 13245.6 13433.9 14236.5 14316.0 14800.8 14805.3 14943.2 15119.2 12669.2 12808.0 13052.6 13167.6 13172.5 12892.4 13155.7 13264.9 13466.3 13523.9 13039.5 13419.3 13491.3 13493.6 13794.2 12802.0 12831.3 12863.9 13133.8 13434.5 12826.3 13188.0 13258.6 13414.5 13789.7 14:07:46 INFO - PROCESS | 19369 | 14:07:46 INFO - PROCESS | 19369 | [#3] mootools.html Cycles:180 Average:6308.34 Median:2668.18 stddev:3643.38 (136.5%) stddev-sans-first:3635.94 14:07:46 INFO - PROCESS | 19369 | Values: 1535.0 1593.3 1730.9 1854.5 1860.4 9122.8 9883.1 9891.1 9894.0 9916.2 8297.2 9311.0 9506.6 9557.1 9714.6 2469.3 2498.6 2499.8 2500.1 2501.1 2469.9 2492.5 2509.5 2510.3 2519.4 2468.0 2470.2 2471.3 2475.4 2492.1 2471.7 2483.5 2490.6 2509.0 2511.4 2422.7 2449.7 2462.9 2466.4 2506.0 9684.4 9843.7 9848.8 9857.9 9864.0 5639.8 5718.9 5769.7 5778.1 5782.0 3365.0 3390.6 3464.6 3492.0 3531.5 1139.1 1175.4 1193.7 1209.3 1216.7 13373.9 13425.3 13426.6 13440.3 13457.1 4849.6 4963.6 4986.7 5087.2 5136.1 11224.9 11263.0 11264.0 11275.8 11277.6 5766.6 5775.0 5777.3 5786.0 5787.5 4644.5 4774.3 5228.6 5254.1 5280.3 4319.9 4392.2 4426.2 4451.0 4470.9 1365.4 1386.1 1393.5 1394.3 1411.3 893.5 928.3 985.9 993.9 1000.6 6058.8 6340.7 6484.8 6550.4 6576.8 479.0 482.1 486.7 496.4 497.5 4197.8 4232.0 4345.4 4384.8 4394.4 11763.4 11895.9 11989.5 12003.8 12008.4 11895.2 11918.8 11919.6 11922.2 11934.0 8522.4 8756.6 8776.9 8923.9 8976.8 6293.8 6356.6 6385.9 6533.2 6572.4 10947.5 11052.8 11063.9 11170.7 11194.4 10067.2 10890.4 11009.0 11111.4 11244.8 8909.0 9194.7 9228.1 9286.6 9388.8 8815.4 8957.1 8962.3 8969.6 8970.5 8387.6 8436.1 8625.9 8630.7 8671.1 8267.7 8279.2 8314.2 8480.1 8603.8 7305.6 7451.7 7532.0 7669.6 7754.1 5626.6 5751.6 5800.0 5805.2 5982.8 5702.9 5835.7 5863.3 5900.6 5938.5 14:07:46 INFO - PROCESS | 19369 | 14:07:46 INFO - PROCESS | 19369 | [#4] prototype.html Cycles:180 Average:4772.69 Median:5334.14 stddev:1399.67 (26.2%) stddev-sans-first:1363.77 14:07:46 INFO - PROCESS | 19369 | Values: 356.0 360.4 360.8 362.8 367.3 5510.6 5541.7 5594.2 5599.0 5644.3 5463.5 5538.4 5551.3 5553.6 5560.4 4144.5 4267.6 4282.5 4294.2 4298.9 4235.0 4264.0 4270.2 4271.1 4316.8 4234.5 4252.0 4258.2 4273.3 4297.7 4198.9 4241.0 4267.1 4271.4 4297.2 3697.8 3701.3 3703.9 3712.7 3715.3 5355.9 5385.9 5390.2 5423.7 5454.0 4758.2 4891.8 4900.7 4906.9 4969.0 5695.6 5696.7 5713.5 5727.3 5728.7 4201.3 4215.9 4223.5 4227.6 4232.0 5893.6 5941.8 5982.0 6001.8 6013.1 4263.4 4263.9 4268.5 4300.8 4315.0 4347.9 4354.9 4356.9 4357.2 4377.9 4712.6 4849.7 4912.0 5006.3 5008.8 5696.5 5719.6 5726.3 5727.8 5737.1 1467.9 1497.5 1497.7 1499.9 1500.3 912.3 954.8 967.9 977.6 977.6 4883.3 4956.4 4986.9 5021.3 5030.8 5200.9 5235.8 5255.9 5275.6 5278.5 4735.6 4943.8 4984.2 4995.5 5047.3 2772.9 2779.7 2794.7 2796.5 2808.2 5323.2 5326.9 5341.3 5364.0 5415.5 5006.5 5240.4 5256.8 5313.7 5715.6 5936.9 6023.9 6060.9 6095.5 6122.9 5914.5 5957.2 5980.9 5999.2 6004.0 5582.1 5862.8 5901.4 5918.2 5940.3 6133.6 6168.4 6178.2 6191.8 6196.0 4136.5 4332.2 4358.6 4359.7 4362.7 5891.3 5908.3 5912.2 5944.8 5945.5 5802.2 5823.5 5838.1 5852.3 5855.7 5669.2 5741.2 5761.7 5770.2 5823.2 5698.9 5701.0 5709.8 5744.2 5759.2 5636.5 5842.2 5886.1 5886.8 5889.7 5649.9 5668.7 5678.4 5683.3 5684.4 14:07:46 INFO - PROCESS | 19369 | 14:07:46 INFO - PROCESS | 19369 | [#5] yui.html Cycles:180 Average:22303.27 Median:4245.48 stddev:31233.12 (735.7%) stddev-sans-first:31278.91 14:07:46 INFO - PROCESS | 19369 | Values: 777.5 799.4 855.8 859.0 925.4 17147.6 18154.3 18263.7 18345.2 18663.8 16629.3 17038.4 17190.6 17880.6 18121.2 840.7 844.2 844.6 849.7 877.7 828.5 831.2 831.4 831.5 834.2 876.2 876.7 876.7 876.9 879.0 869.7 870.1 870.3 871.0 877.1 634.7 634.7 635.1 635.2 640.5 15091.4 15503.8 16771.2 17015.7 17045.0 16192.4 16417.6 16432.8 16580.3 16916.9 13277.6 13667.2 13771.3 13815.3 14159.4 4150.5 4233.4 4257.5 4274.5 4276.6 89134.6 89438.0 90063.0 90811.0 93306.5 12494.0 12513.5 13031.7 13131.1 13329.9 24780.3 24794.6 24935.5 24944.8 25615.5 8327.0 8437.5 8471.2 8664.6 9253.1 4872.5 4944.6 4980.4 5074.2 5164.8 3747.4 3863.7 3867.1 3869.0 3871.4 658.1 660.6 664.0 666.8 687.1 742.8 755.3 772.4 784.0 808.5 9849.5 10113.5 10380.7 10453.8 10517.0 648.2 693.8 720.5 732.1 740.9 4877.4 5164.7 5369.2 5380.4 5404.5 76516.6 77567.6 121182.0 132720.3 135261.0 75620.0 76119.0 92453.0 93547.5 116316.4 92897.0 96798.5 98900.5 99409.0 100020.0 10205.1 10439.6 10443.5 10494.8 10506.7 54189.0 54855.0 55779.0 56150.7 56473.4 80134.0 82393.2 84906.0 85164.1 86994.1 13734.3 13976.9 14168.6 14206.6 14445.4 15747.0 15772.1 15828.8 15994.8 16770.9 14774.3 14877.4 15190.8 15521.8 16866.4 15028.4 15115.2 15288.8 15710.8 15752.8 14298.7 14532.9 14565.6 15185.0 15848.0 12208.1 12305.9 12535.2 12784.4 12876.2 12666.1 12786.2 12889.7 12933.4 13125.0 14:07:46 INFO - PROCESS | 19369 | -------- Summary: end -------- 14:07:46 INFO - PROCESS | 19369 | 14:07:46 INFO - TEST-INFO | 19369: exit 0 14:07:46 INFO - TEST-OK | dromaeo_css | took 1112628ms 14:07:46 INFO - TEST-START | kraken 14:07:46 INFO - operating with platform_type : linux_ 14:07:46 INFO - Initialising browser for kraken test... 14:07:46 INFO - TEST-INFO | started process 19572 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNMB_37/profile http://localhost:49654/getInfo.html) 14:07:50 INFO - PROCESS | 19572 | __metrics Screen width/height:1600/1200 14:07:50 INFO - PROCESS | 19572 | colorDepth:24 14:07:50 INFO - PROCESS | 19572 | Browser inner width/height: 1024/697 14:07:50 INFO - PROCESS | 19572 | __metrics 14:07:51 INFO - PROCESS | 19572 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:07:51 INFO - PROCESS | 19572 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:07:52 INFO - TEST-INFO | 19572: exit 0 14:07:52 INFO - Browser initialized. 14:07:52 INFO - Running cycle 1/1 for kraken test... 14:07:52 INFO - TEST-INFO | started process 19660 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNMB_37/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:08:04 INFO - PROCESS | 19660 | RSS: Main: 145457152 14:08:04 INFO - PROCESS | 19660 | 14:08:06 INFO - PROCESS | 19660 | Cycle 1(1): loaded http://localhost:49654/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49654/tests/kraken/driver.html?testName=audio-beat-detection) 14:08:07 INFO - PROCESS | 19660 | RSS: Main: 190423040 14:08:07 INFO - PROCESS | 19660 | 14:08:10 INFO - PROCESS | 19660 | Cycle 1(1): loaded http://localhost:49654/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49654/tests/kraken/driver.html?testName=audio-dft) 14:08:10 INFO - PROCESS | 19660 | RSS: Main: 181325824 14:08:10 INFO - PROCESS | 19660 | 14:08:13 INFO - PROCESS | 19660 | Cycle 1(1): loaded http://localhost:49654/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49654/tests/kraken/driver.html?testName=audio-fft) 14:08:14 INFO - PROCESS | 19660 | RSS: Main: 269627392 14:08:14 INFO - PROCESS | 19660 | 14:08:16 INFO - PROCESS | 19660 | Cycle 1(1): loaded http://localhost:49654/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49654/tests/kraken/driver.html?testName=audio-oscillator) 14:08:16 INFO - PROCESS | 19660 | RSS: Main: 175251456 14:08:16 INFO - PROCESS | 19660 | 14:08:18 INFO - PROCESS | 19660 | Cycle 1(1): loaded http://localhost:49654/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49654/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:08:19 INFO - PROCESS | 19660 | RSS: Main: 177176576 14:08:19 INFO - PROCESS | 19660 | 14:08:22 INFO - PROCESS | 19660 | Cycle 1(1): loaded http://localhost:49654/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49654/tests/kraken/driver.html?testName=imaging-darkroom) 14:08:23 INFO - PROCESS | 19660 | RSS: Main: 212873216 14:08:23 INFO - PROCESS | 19660 | 14:08:26 INFO - PROCESS | 19660 | Cycle 1(1): loaded http://localhost:49654/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49654/tests/kraken/driver.html?testName=imaging-desaturate) 14:08:27 INFO - PROCESS | 19660 | RSS: Main: 211685376 14:08:27 INFO - PROCESS | 19660 | 14:08:29 INFO - PROCESS | 19660 | Cycle 1(1): loaded http://localhost:49654/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49654/tests/kraken/driver.html?testName=json-parse-financial) 14:08:30 INFO - PROCESS | 19660 | RSS: Main: 211361792 14:08:30 INFO - PROCESS | 19660 | 14:08:31 INFO - PROCESS | 19660 | Cycle 1(1): loaded http://localhost:49654/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49654/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:08:32 INFO - PROCESS | 19660 | RSS: Main: 175632384 14:08:32 INFO - PROCESS | 19660 | 14:08:34 INFO - PROCESS | 19660 | Cycle 1(1): loaded http://localhost:49654/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49654/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:08:34 INFO - PROCESS | 19660 | RSS: Main: 181616640 14:08:34 INFO - PROCESS | 19660 | 14:08:36 INFO - PROCESS | 19660 | Cycle 1(1): loaded http://localhost:49654/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49654/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:08:37 INFO - PROCESS | 19660 | RSS: Main: 185933824 14:08:37 INFO - PROCESS | 19660 | 14:08:39 INFO - PROCESS | 19660 | Cycle 1(1): loaded http://localhost:49654/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49654/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:08:40 INFO - PROCESS | 19660 | RSS: Main: 181452800 14:08:40 INFO - PROCESS | 19660 | 14:08:43 INFO - PROCESS | 19660 | Cycle 1(1): loaded http://localhost:49654/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49654/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:08:43 INFO - PROCESS | 19660 | RSS: Main: 184455168 14:08:43 INFO - PROCESS | 19660 | 14:08:45 INFO - PROCESS | 19660 | Cycle 1(1): loaded http://localhost:49654/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49654/tests/kraken/driver.html?testName=ai-astar) 14:08:45 INFO - PROCESS | 19660 | RSS: Main: 235421696 14:08:45 INFO - PROCESS | 19660 | 14:08:45 INFO - PROCESS | 19660 | __start_tp_report 14:08:45 INFO - PROCESS | 19660 | _x_x_mozilla_page_load 14:08:45 INFO - PROCESS | 19660 | _x_x_mozilla_page_load_details 14:08:45 INFO - PROCESS | 19660 | |i|pagename|runs| 14:08:45 INFO - PROCESS | 19660 | |0;ai-astar;102;111;110;109;110;110;108;108;109;110 14:08:45 INFO - PROCESS | 19660 | |1;audio-beat-detection;143;114;115;177;118;118;138;114;157;120 14:08:45 INFO - PROCESS | 19660 | |2;audio-dft;171;161;161;160;161;161;162;162;160;159 14:08:45 INFO - PROCESS | 19660 | |3;audio-fft;74;74;74;109;75;75;76;74;103;74 14:08:45 INFO - PROCESS | 19660 | |4;audio-oscillator;84;82;132;83;84;82;120;83;83;129 14:08:45 INFO - PROCESS | 19660 | |5;imaging-gaussian-blur;117;118;119;118;117;117;117;117;117;117 14:08:45 INFO - PROCESS | 19660 | |6;imaging-darkroom;145;146;146;145;146;146;145;146;146;146 14:08:45 INFO - PROCESS | 19660 | |7;imaging-desaturate;94;95;95;94;94;95;94;95;95;94 14:08:45 INFO - PROCESS | 19660 | |8;json-parse-financial;50;50;51;50;50;50;50;51;50;53 14:08:45 INFO - PROCESS | 19660 | |9;json-stringify-tinderbox;60;59;59;59;59;59;59;59;59;59 14:08:45 INFO - PROCESS | 19660 | |10;stanford-crypto-aes;72;75;72;74;81;73;72;73;74;72 14:08:45 INFO - PROCESS | 19660 | |11;stanford-crypto-ccm;125;115;123;123;128;116;123;121;160;131 14:08:45 INFO - PROCESS | 19660 | |12;stanford-crypto-pbkdf2;153;152;150;154;153;154;151;152;149;155 14:08:45 INFO - PROCESS | 19660 | |13;stanford-crypto-sha256-iterative;63;61;61;60;61;62;65;61;60;61 14:08:45 INFO - PROCESS | 19660 | __end_tp_report 14:08:45 INFO - PROCESS | 19660 | __start_cc_report 14:08:45 INFO - PROCESS | 19660 | _x_x_mozilla_cycle_collect,1639 14:08:45 INFO - PROCESS | 19660 | __end_cc_report 14:08:45 INFO - PROCESS | 19660 | __startTimestamp1472591325597__endTimestamp 14:08:45 INFO - PROCESS | 19660 | 14:08:45 INFO - PROCESS | 19660 | ------- Summary: start ------- 14:08:45 INFO - PROCESS | 19660 | Number of tests: 14 14:08:45 INFO - PROCESS | 19660 | 14:08:45 INFO - PROCESS | 19660 | [#0] ai-astar Cycles:10 Average:108.70 Median:110.00 stddev:2.54 (2.3%) stddev-sans-first:1.01 14:08:45 INFO - PROCESS | 19660 | Values: 102.0 111.0 110.0 109.0 110.0 110.0 108.0 108.0 109.0 110.0 14:08:45 INFO - PROCESS | 19660 | 14:08:45 INFO - PROCESS | 19660 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:129.00 stddev:21.80 (16.9%) stddev-sans-first:22.71 14:08:45 INFO - PROCESS | 19660 | Values: 143.0 114.0 115.0 177.0 118.0 118.0 138.0 114.0 157.0 120.0 14:08:45 INFO - PROCESS | 19660 | 14:08:45 INFO - PROCESS | 19660 | [#2] audio-dft Cycles:10 Average:161.80 Median:161.00 stddev:3.36 (2.1%) stddev-sans-first:0.97 14:08:45 INFO - PROCESS | 19660 | Values: 171.0 161.0 161.0 160.0 161.0 161.0 162.0 162.0 160.0 159.0 14:08:45 INFO - PROCESS | 19660 | 14:08:45 INFO - PROCESS | 19660 | [#3] audio-fft Cycles:10 Average:80.80 Median:74.00 stddev:13.37 (18.1%) stddev-sans-first:13.96 14:08:45 INFO - PROCESS | 19660 | Values: 74.0 74.0 74.0 109.0 75.0 75.0 76.0 74.0 103.0 74.0 14:08:45 INFO - PROCESS | 19660 | 14:08:45 INFO - PROCESS | 19660 | [#4] audio-oscillator Cycles:10 Average:96.20 Median:83.00 stddev:21.47 (25.9%) stddev-sans-first:22.31 14:08:45 INFO - PROCESS | 19660 | Values: 84.0 82.0 132.0 83.0 84.0 82.0 120.0 83.0 83.0 129.0 14:08:45 INFO - PROCESS | 19660 | 14:08:45 INFO - PROCESS | 19660 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 14:08:45 INFO - PROCESS | 19660 | Values: 117.0 118.0 119.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 14:08:45 INFO - PROCESS | 19660 | 14:08:45 INFO - PROCESS | 19660 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 14:08:45 INFO - PROCESS | 19660 | Values: 145.0 146.0 146.0 145.0 146.0 146.0 145.0 146.0 146.0 146.0 14:08:45 INFO - PROCESS | 19660 | 14:08:45 INFO - PROCESS | 19660 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 14:08:45 INFO - PROCESS | 19660 | Values: 94.0 95.0 95.0 94.0 94.0 95.0 94.0 95.0 95.0 94.0 14:08:45 INFO - PROCESS | 19660 | 14:08:45 INFO - PROCESS | 19660 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:50.00 stddev:0.97 (1.9%) stddev-sans-first:1.01 14:08:45 INFO - PROCESS | 19660 | Values: 50.0 50.0 51.0 50.0 50.0 50.0 50.0 51.0 50.0 53.0 14:08:45 INFO - PROCESS | 19660 | 14:08:45 INFO - PROCESS | 19660 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:0.32 (0.5%) stddev-sans-first:0.00 14:08:45 INFO - PROCESS | 19660 | Values: 60.0 59.0 59.0 59.0 59.0 59.0 59.0 59.0 59.0 59.0 14:08:45 INFO - PROCESS | 19660 | 14:08:45 INFO - PROCESS | 19660 | [#10] stanford-crypto-aes Cycles:10 Average:73.80 Median:73.50 stddev:2.74 (3.7%) stddev-sans-first:2.83 14:08:45 INFO - PROCESS | 19660 | Values: 72.0 75.0 72.0 74.0 81.0 73.0 72.0 73.0 74.0 72.0 14:08:45 INFO - PROCESS | 19660 | 14:08:45 INFO - PROCESS | 19660 | [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:124.00 stddev:12.72 (10.3%) stddev-sans-first:13.48 14:08:45 INFO - PROCESS | 19660 | Values: 125.0 115.0 123.0 123.0 128.0 116.0 123.0 121.0 160.0 131.0 14:08:45 INFO - PROCESS | 19660 | 14:08:45 INFO - PROCESS | 19660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.30 Median:153.00 stddev:1.89 (1.2%) stddev-sans-first:1.99 14:08:45 INFO - PROCESS | 19660 | Values: 153.0 152.0 150.0 154.0 153.0 154.0 151.0 152.0 149.0 155.0 14:08:45 INFO - PROCESS | 19660 | 14:08:45 INFO - PROCESS | 19660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:61.00 stddev:1.51 (2.5%) stddev-sans-first:1.50 14:08:45 INFO - PROCESS | 19660 | Values: 63.0 61.0 61.0 60.0 61.0 62.0 65.0 61.0 60.0 61.0 14:08:45 INFO - PROCESS | 19660 | -------- Summary: end -------- 14:08:45 INFO - PROCESS | 19660 | 14:08:46 INFO - TEST-INFO | 19660: exit 0 14:08:46 INFO - TEST-OK | kraken | took 59909ms 14:08:46 INFO - SUITE-END | took 1172s 14:08:47 INFO - Completed test suite (00:19:33) 14:08:47 INFO - javascript benchmark 14:08:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3226.322472280821, 3264.4940034294677, 3265.2489927316715, 3294.028261662809, 3308.0406682062203, 5623.578231632629, 5714.428557144294, 5732.31212254529, 5746.827595172141, 5751.712414379274, 5590.524805391547, 5668.036433806253, 5684.459976302255, 5687.886242275152, 5688.829335119953, 4988.176950803131, 5102.974485127569, 5140.203268493371, 5146.845594632157, 5176.741162941857, 5107.284980102789, 5161.148410512261, 5164.457731938149, 5164.586833053366, 5165.8450246492475, 5118.769655365518, 5141.948580514184, 5157.200633901752, 5176.870578235536, 5177.534021938025, 5183.222516622499, 5184.196449550326, 5200.453952334989, 5210.192420174879, 5236.816711415101, 5055.266986286968, 5081.441041485434, 5085.186370180791, 5121.820736274212, 5126.5642420394315, 5630.40880707528, 5638.2670252866865, 5676.148577713335, 5682.318121825366, 5735.397783232742, 6164.383561643845, 6172.074188871705, 6176.289726681444, 6182.28903676074, 6182.412670796267, 5983.58114931955, 6006.729697163594, 6009.849753756147, 6097.786577469812, 6221.626702397871, 5673.716314184274, 5688.146777983336, 5692.943070569283, 5699.629524080963, 5700.686462244534, 5997.2803263607775, 6168.414000670019, 6169.691515424211, 6176.536759743038, 6183.659898705614, 5831.183634291203, 5863.442972917566, 5869.442402971711, 5962.761489540369, 5962.910556341659, 6011.158437818709, 6044.667543285073, 6048.0625503047095, 6050.183225264621, 6051.273847138284, 5893.0, 5984.611000284967, 5986.4013598639785, 5988.700564971821, 5991.850203744942, 6006.609570377999, 6044.758209671564, 6059.424354686298, 6113.202667687911, 6113.628337950562, 4683.578477937002, 4691.437027556715, 4694.88262793426, 4718.646101542329, 4721.055788842245, 4137.275976704064, 4183.142455796555, 4193.119444916601, 4194.979025104855, 4203.726757760735, 5784.768609255582, 5805.419458054161, 5823.155642431823, 5881.647101173943, 5917.378675239124, 6058.606190597685, 6100.450959413674, 6101.145839582462, 6107.603005804697, 6129.969350153221, 5674.375818659944, 5729.054705973551, 5802.0426629605645, 5802.854928626818, 5814.5348372130975, 5179.171332586843, 5226.242194881797, 5226.477352264743, 5267.78928842842, 5293.100172970604, 6253.593516421418, 6258.998560230341, 6265.780697675559, 6266.561340706106, 6267.874642507124, 6032.125341825323, 6065.6663883486835, 6068.453839154321, 6068.757249710139, 6069.089636554376, 6136.846578835565, 6136.917362303904, 6144.986077297196, 6201.844953876189, 6261.499080073674, 5924.0, 5933.0, 5938.970305148447, 5947.524198064231, 5974.820755377345, 5944.910826337522, 5946.167536544801, 6038.788642397495, 6039.033754599243, 6045.1234570987845, 5918.970405147947, 5948.553858460719, 5949.8215053548465, 6020.969895150496, 6047.274327080779, 5966.70166491665, 5975.0, 5978.910316345171, 5982.0, 5984.910226346521, 5788.305403351625, 5936.28764548257, 5962.761489540543, 5967.403259674172, 5984.700764961647, 5892.410758924245, 5902.61632993854, 5954.285485741739, 6000.459958603747, 6039.698015099315, 5824.5340372770925, 5829.8251052468495, 5839.0, 5844.2986841577585, 5845.210896528914], "value": 5594.941859062623, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.2977740487142, 632.1434456311697, 645.1806206118233, 648.367841354679, 659.7328082126737, 34285.142871428194, 37003.25993480129, 45566.31650525245, 46663.53336466634, 46716.76641616787, 46365.30452043222, 46916.0, 46974.0, 46991.29513057298, 47047.29429058567, 8302.0, 9238.491882946435, 9370.250379969586, 9380.24958003358, 9381.15569598736, 10818.0, 11100.056495197918, 11125.888741112567, 11378.829317560201, 11390.430478476042, 11228.101751859831, 11298.322100673986, 11327.716807079807, 11331.943340283287, 11335.886641133608, 11045.226834121575, 11071.612493562767, 11090.613673290838, 11102.666919992373, 11105.500252488657, 10978.945105274503, 11042.558297668083, 11053.392063436508, 11066.446677666085, 11066.612668556643, 44711.0, 45126.0, 45211.32183017257, 45226.32160517595, 45234.54765452336, 3803.4675145479655, 4006.346292009246, 4021.77880216588, 4317.740935543846, 4652.395188625491, 35123.297534049176, 35283.0, 35693.82153089244, 36349.454758178385, 36486.08784780375, 4565.155446242456, 4669.626429885635, 4728.099387799857, 4759.881002974918, 4928.063667903123, 360435.0, 361276.0, 362254.1887290573, 362560.0, 362956.0, 36855.4471682925, 59994.0, 60334.0, 60343.39656603466, 60363.0, 37624.0, 39629.40555891664, 39680.404793928115, 39685.20629587392, 39709.40435893464, 16530.917345413196, 16629.91685041567, 16645.6670866582, 16655.33378664864, 16675.666486670198, 13218.339083045808, 13821.654458638615, 13829.03196776216, 14353.354099065567, 14704.55886323412, 9041.77395565116, 9055.683051093312, 9078.90921090794, 9101.226395756436, 9139.131782480654, 3468.8439020243645, 3483.7038851697384, 3517.56030496188, 3520.2431477232517, 3573.5890372606964, 973.7662960889336, 1066.4081434803759, 1118.9104871610252, 1151.8732939376662, 1202.4228370382266, 34799.4780078299, 35047.0, 35188.296234075686, 35547.82226088853, 35770.0, 981.8527220916777, 1183.4260879045753, 1189.5004098278744, 1242.726600147966, 1251.9687007824878, 18242.25369008758, 18312.175952082187, 18397.080145992648, 18406.079696015146, 18548.350807721665, 560689.0, 560807.0, 562351.0, 563246.0, 564850.0, 62655.37344626495, 63150.36849631445, 63342.36657633457, 63415.0, 63449.36550634527, 1301.5184381778802, 1456.5120684570788, 1467.5890750589851, 1472.2417954753312, 1474.5576327101826, 31658.0, 35115.82442087773, 36351.27297453983, 37100.44349334816, 37278.627213727515, 56416.153757692846, 57004.0, 57205.42794572001, 57330.713346433, 57390.0, 477378.0, 478039.0, 480440.0, 481613.0, 593139.0, 46488.53511464842, 48277.0, 48878.26682599693, 49181.262281065094, 49941.750291249766, 52630.0, 52751.47248527466, 52845.20732188944, 52920.0, 53341.19988200103, 44832.72341872075, 45321.0, 46269.768651156526, 46715.29927051029, 47777.522224777305, 44539.55460445484, 45173.77413112913, 45387.54612453965, 45659.315110272706, 46901.2964805535, 44946.550534494236, 45806.54193458156, 45984.770076149405, 46158.0, 46279.53720462887, 37285.25429491341, 39748.20503589854, 39813.2037359257, 40733.981650458976, 40785.79607102064, 29576.852115739282, 29578.408431831675, 30025.983833385813, 30465.54301685432, 30654.540181897737], "value": 21039.157434576875, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.4766508209165, 417.5803317665747, 422.674540603735, 425.2175996167052, 441.569469766977, 15589.45436909707, 19624.70562941557, 20052.0, 21482.677759833616, 21534.461638459008, 19354.709679354823, 20114.195432182696, 20446.897765511152, 21015.159393624235, 21211.0, 5921.911171332423, 5973.044312909935, 6090.5736598438125, 6097.725602347883, 6100.389961003886, 5851.941480585204, 5866.120081987693, 5926.259217597801, 5927.436893495111, 6047.123167140784, 5914.290285165786, 5943.673097979609, 6012.068129439944, 6019.21750172477, 6029.728662210189, 5861.2087368205175, 5969.373215812342, 5980.073088671263, 5984.8503787405225, 5991.880162396771, 5346.144616861322, 5355.705436201008, 5398.8920221595345, 5406.945930540723, 5416.539594134503, 24455.38861528476, 24561.14036008749, 24664.13675521366, 24677.259682209562, 25130.24609261725, 2403.1468828565958, 2564.2050964201158, 2587.857667828269, 2827.2790438438274, 3037.1344166912545, 15500.844991550053, 15651.921740391339, 15770.290336934866, 15917.363305467767, 15995.840041599551, 2058.1561559760576, 2069.0172168220124, 2069.9482512937298, 2132.445564153316, 2210.7789221077924, 83227.0, 84367.5781621094, 85428.57285713531, 85836.0, 86080.56959715224, 10211.642592509375, 10447.84328235077, 11194.888051119384, 11261.49323280438, 11382.94308528452, 15285.235738213045, 16129.758053629204, 16442.917785410995, 16590.004599724056, 16598.834011659972, 8501.107383724744, 8705.390622656354, 8854.778630534289, 8911.0930867871, 9075.13786190311, 8020.879686804703, 8050.194980501903, 8295.8340833183, 8581.056083830774, 8898.55507224649, 7040.155181378267, 7152.785416437411, 7168.2831716827895, 7192.172900116449, 7217.639118044077, 2015.2241387065792, 2040.8061383934096, 2060.484878780305, 2094.0786054136133, 2139.6362618354915, 919.9494027828533, 961.5624890674866, 1040.8074506216374, 1070.1866581398037, 1103.9613613523488, 22131.225407110673, 22164.1134354624, 22708.545829083327, 23025.7697423027, 23138.652920206212, 987.1362557761959, 1019.928604997643, 1030.0832259289239, 1075.3010543147018, 1127.1602656021153, 10895.097206257216, 11527.71180720472, 11590.246633968934, 11610.361430121255, 11638.767224655629, 454093.0, 461042.0, 461131.0, 481646.0, 520411.0, 60827.0, 97040.0, 97750.0, 99504.0, 100348.0, 1006.5872992073362, 1011.9342242754198, 1027.121810851711, 1032.0402026115635, 1104.1498046504146, 14294.857051429768, 17959.640807184045, 19234.422967311002, 19289.807101929364, 19491.51271218231, 44201.77899110484, 44341.0, 45805.54194458013, 45987.77006114948, 46421.0, 55746.442535574126, 57920.0, 59735.40264597299, 60258.69870650794, 60912.69543652253, 8707.47755134694, 12061.0, 13245.602631921058, 13433.865661343261, 14236.501722439665, 14316.0, 14800.7779883304, 14805.259737012891, 14943.178125203218, 15119.244037798286, 12669.176503527242, 12808.0, 13052.608421747364, 13167.60497185086, 13172.473101076233, 12892.355382231039, 13155.671108222372, 13264.867351326364, 13466.259355735529, 13523.932380338036, 13039.47842086344, 13419.261940593362, 13491.25798081115, 13493.595192144252, 13794.172349659053, 12802.039847011325, 12831.294278814756, 12863.935680321538, 13133.802992955305, 13434.529791457253, 12826.294553799631, 13188.0, 13258.602241932758, 13414.463421463422, 13789.655258618615], "value": 10171.714852611696, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1535.00224853845, 1593.3148746039203, 1730.8788384813067, 1854.5120807645505, 1860.4418674397677, 9122.771930701736, 9883.061109194632, 9891.060349266818, 9893.950530247339, 9916.206703463724, 8297.1702829717, 9310.95344523278, 9506.572204250808, 9557.139857412849, 9714.61141554337, 2469.2839076667747, 2498.612715029173, 2499.825012249143, 2500.1499490173337, 2501.062101711858, 2469.901203951849, 2492.513959777844, 2509.473010667762, 2510.2845688978646, 2519.407939134302, 2468.049800826677, 2470.2218801077693, 2471.258622413278, 2475.418276704982, 2492.090387008747, 2471.715752688437, 2483.466054798218, 2490.6139548370033, 2508.987455062713, 2511.4223728542356, 2422.6729391532094, 2449.669294645218, 2462.8891699873548, 2466.3957330453973, 2506.0477018732763, 9684.41893486393, 9843.655472058515, 9848.753781155458, 9857.90142098577, 9863.950680246624, 5639.802606908738, 5718.914216286759, 5769.682667453289, 5778.075507918755, 5781.971090144564, 3365.0, 3390.593128824557, 3464.636213197603, 3491.9825400873087, 3531.499112877017, 1139.1399493382526, 1175.435790820411, 1193.653840386287, 1209.2623499665197, 1216.7444836584327, 13373.866261337456, 13425.261610611413, 13426.597202083856, 13440.260785656785, 13457.05800593949, 4849.636277279218, 4963.553280204799, 4986.7257300848805, 5087.23691446286, 5136.126858434075, 11224.94387528057, 11263.0, 11264.042556382636, 11275.774484510264, 11277.605283815026, 5766.596338256366, 5775.047117225611, 5777.335606405233, 5786.016377215883, 5787.479126878517, 4644.465886423037, 4774.283857421415, 5228.6339956203465, 5254.106801843695, 5280.287161233185, 4319.892002699895, 4392.1874453226255, 4426.22541055314, 4451.0, 4470.865874023784, 1365.4060483689627, 1386.105961654727, 1393.470481217131, 1394.2819447984295, 1411.2590889782946, 893.4907102951255, 928.331601247101, 985.9014098590084, 993.9304248702522, 1000.5547531348626, 6058.848528786727, 6340.7463701451425, 6484.837879052967, 6550.377714117151, 6576.76981305652, 478.9856304310876, 482.0623886540645, 486.7250003747871, 496.42910652748907, 497.4577710295795, 4197.811098500575, 4232.047789247456, 4345.435093437833, 4384.802683879169, 4394.362817391524, 11763.411829408666, 11895.92936635707, 11989.520419183309, 12003.759924801456, 12008.399580020963, 11895.226810257478, 11918.761624767456, 11919.642410727693, 11922.225055371546, 11934.0, 8522.446041007315, 8756.605952732016, 8776.91223087761, 8923.910760892439, 8976.820463590691, 6293.811185664437, 6356.618602883842, 6385.936140638534, 6533.248676402274, 6572.4084832365315, 10947.45262736844, 11052.834207486734, 11063.944680276547, 11170.720731981766, 11194.440277985905, 10067.244956628429, 10890.401027943539, 11009.0, 11111.388873611706, 11244.775104498029, 8908.955455222682, 9194.678186263449, 9228.123328283802, 9286.582103805209, 9388.765280868032, 8815.382923195315, 8957.059508751749, 8962.32782541299, 8969.641214351353, 8970.461772293684, 8387.622556984828, 8436.072032076589, 8625.913740862761, 8630.697925572575, 8671.089535598923, 8267.710630127916, 8279.172082791672, 8314.16858314188, 8480.06719260893, 8603.784905377415, 7305.634718263959, 7451.701931922662, 7531.962340188264, 7669.61651917413, 7754.147043825062, 5626.606137570331, 5751.626144300607, 5799.971000144972, 5805.216295800182, 5982.790602328898, 5702.914456283242, 5835.716142448655, 5863.325717542367, 5900.64596124234, 5938.524918006463], "value": 4928.499836903752, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.99288014239715, 360.35316606690986, 360.77271319068984, 362.77145398398994, 367.32962343722716, 5510.614257002011, 5541.667499950006, 5594.216809646653, 5598.972005139974, 5644.294463192101, 5463.453654634534, 5538.418466061066, 5551.333839939213, 5553.583481238903, 5560.443955604437, 4144.46122004139, 4267.637250833683, 4282.464691913511, 4294.184105020052, 4298.935515967248, 4234.983603935048, 4263.957360426402, 4270.210011147948, 4271.145770845827, 4316.784160791964, 4234.491860976687, 4251.957480425187, 4258.233517966764, 4273.337632666941, 4297.720648157875, 4198.937015944748, 4240.978795106035, 4267.103908179255, 4271.444712187395, 4297.247981603223, 3697.759645623026, 3701.2782507411066, 3703.9444408333898, 3712.6658600726064, 3715.2755212733528, 5355.892882142374, 5385.919211211835, 5390.245365648812, 5423.674579525241, 5453.963746888078, 4758.214894542396, 4891.779869905863, 4900.730459824708, 4906.877328066791, 4968.950310496885, 5695.601307908448, 5696.6866822324755, 5713.485786279213, 5727.284089488814, 5728.656280623176, 4201.34879093741, 4215.852445164435, 4223.5142958559545, 4227.62602154298, 4232.026633874196, 5893.646381217098, 5941.821745347646, 5982.0, 6001.819945401645, 6013.067974463965, 4263.381809637585, 4263.850765223202, 4268.466441694788, 4300.806463709078, 4314.96440854199, 4347.891302717458, 4354.912901741947, 4356.934645980313, 4357.172137293904, 4377.9124417511475, 4712.5994290485005, 4849.660523763303, 4912.017596480719, 5006.274090256965, 5008.846196135697, 5696.487316141567, 5719.598698318939, 5726.341470730836, 5727.828165155052, 5737.08206686928, 1467.9339429725687, 1497.5207933461188, 1497.7304085264539, 1499.932503037366, 1500.2798656644873, 912.3385545479474, 954.7947191353892, 967.9467629280316, 977.5649835823133, 977.5991843344194, 4883.267509873547, 4956.447429573773, 4986.850394488171, 5021.322121513622, 5030.823921162815, 5200.92198617021, 5235.842924712265, 5255.894882102337, 5275.60432967529, 5278.4721527846905, 4735.602997115859, 4943.8022479101155, 4984.181937563456, 4995.475475075137, 5047.268146118792, 2772.916812495628, 2779.7359250871136, 2794.720527947189, 2796.517845541866, 2808.227737372198, 5323.22813192085, 5326.946730532645, 5341.332333458317, 5363.973180134152, 5415.48552887475, 5006.524380183877, 5240.423553409122, 5256.763445644955, 5313.654612450178, 5715.628484148517, 5936.883136353449, 6023.879522409527, 6060.878782424327, 6095.542834287447, 6122.938770612237, 5914.526837852875, 5957.195778570011, 5980.9401905980385, 5999.190109335358, 6004.0, 5582.134769110794, 5862.765489380377, 5901.380355062656, 5918.165368735706, 5940.346561878277, 6133.601315914451, 6168.352323006199, 6178.196834411498, 6191.761647670394, 6196.0, 4136.482939632546, 4332.155229730172, 4358.585934336233, 4359.694821362475, 4362.694611377173, 5891.263592050994, 5908.261467316585, 5912.231409916683, 5944.751602163594, 5945.464908158287, 5802.216700745345, 5823.534117270693, 5838.08095952024, 5852.273579294033, 5855.7410156814785, 5669.234653321749, 5741.196232527366, 5761.740721667619, 5770.163326317576, 5823.242978412779, 5698.943010569841, 5701.030824759718, 5709.771609135754, 5744.167095771173, 5759.2434307536605, 5636.5208957237455, 5842.152887831326, 5886.146508756292, 5886.793962211302, 5889.734961926809, 5649.915251271316, 5668.659880407189, 5678.381661226531, 5683.2895888014, 5684.403137670485], "value": 4332.778353536926, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [777.5334799120528, 799.3565180030077, 855.7903313688146, 859.0421679826993, 925.3892430995536, 17147.571310717238, 18154.27382904684, 18263.72604410935, 18345.174467148994, 18663.813361866378, 16629.251683674232, 17038.403655872014, 17190.57023574411, 17880.64238715225, 18121.184546695396, 840.6973489543759, 844.1980118887051, 844.6283635200492, 849.7408290471403, 877.7454538183935, 828.4987582512583, 831.1646794971045, 831.4055450352996, 831.4803247970019, 834.1866679986995, 876.1807709791366, 876.7369789063288, 876.7369789063288, 876.8947726272856, 878.995605021974, 869.7303835810919, 870.1342164546254, 870.3472395703222, 871.0418539606445, 877.1228771228771, 634.7238951056305, 634.7334119669704, 635.1267007864187, 635.2281977397485, 640.5356116815271, 15091.39634414622, 15503.767443488357, 16771.2452939618, 17015.659686806193, 17045.0, 16192.433264835674, 16417.58956026097, 16432.83567164337, 16580.336786528525, 16916.915415422965, 13277.601671949857, 13667.17996920188, 13771.311434428238, 13815.309234538334, 14159.362828672734, 4150.501939767248, 4233.428487154226, 4257.531671516131, 4274.529801721808, 4276.636485898703, 89134.5543272286, 89438.0, 90062.99999999935, 90811.0, 93306.53346733224, 12494.0, 12513.499460021498, 13031.739365212643, 13131.146475479063, 13329.866701332863, 24780.25659230226, 24794.628080578805, 24935.501289974098, 24944.750552494243, 25615.48769024609, 8327.0, 8437.451565648213, 8471.195236452528, 8664.61009254585, 9253.120953509406, 4872.4883887192045, 4944.629152813553, 4980.352554167935, 5074.213496907985, 5164.819231326961, 3747.4191500317493, 3863.652271295597, 3867.091233560111, 3868.9806550967064, 3871.380579107329, 658.111549408295, 660.600336796241, 664.0106241699832, 666.8199586111723, 687.08274453605, 742.8291492956542, 755.2900273742774, 772.3666593393476, 784.0473824303499, 808.5108509351758, 9849.507524623883, 10113.494325283706, 10380.688579342632, 10453.790924181474, 10517.000884916073, 648.2124219073847, 693.7675878580732, 720.5136532840312, 732.1045265973064, 740.8555331710264, 4877.3903262092235, 5164.671030105793, 5369.24830523727, 5380.354357477049, 5404.486573775485, 76516.61741691256, 77567.61216193883, 121182.0, 132720.3363983174, 135261.0, 75620.0, 76119.0, 92453.0, 93547.53226233825, 116316.41841790907, 92897.0, 96798.5160074181, 98900.50549747206, 99409.0, 100020.0, 10205.132563732166, 10439.634612788515, 10443.538528324367, 10494.790104198026, 10506.684799456028, 54189.0, 54855.0, 55779.0, 56150.7192464035, 56473.43526564682, 80134.0, 82393.17606823855, 84906.0, 85164.14835851562, 86994.13005870114, 13734.313284335543, 13976.860231397963, 14168.589280232844, 14206.644833879236, 14445.422183112558, 15747.0, 15772.05367677943, 15828.841711582736, 15994.840051599334, 16770.916145419196, 14774.261286935825, 14877.404903803726, 15190.848091519385, 15521.76717349218, 16866.409675661293, 15028.398864045315, 15115.168665723491, 15288.77066843976, 15710.764338534702, 15752.842471575137, 14298.714025719635, 14532.927335363256, 14565.635859103184, 15185.0, 15848.0, 12208.145429819828, 12305.876941230832, 12535.185212961129, 12784.424700888669, 12876.22742635445, 12666.050046246384, 12786.232826030468, 12889.742205156032, 12933.353332333158, 13124.96584886081], "value": 8211.033762160692, "unit": "score"}], "name": "dromaeo_css", "value": 7709.452891687272, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 110.0, 109.0, 110.0, 110.0, 108.0, 108.0, 109.0, 110.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 114.0, 115.0, 177.0, 118.0, 118.0, 138.0, 114.0, 157.0, 120.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 161.0, 161.0, 160.0, 161.0, 161.0, 162.0, 162.0, 160.0, 159.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 109.0, 75.0, 75.0, 76.0, 74.0, 103.0, 74.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 132.0, 83.0, 84.0, 82.0, 120.0, 83.0, 83.0, 129.0], "value": 96.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 119.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 50.0, 50.0, 50.0, 50.0, 51.0, 50.0, 53.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 72.0, 74.0, 81.0, 73.0, 72.0, 73.0, 74.0, 72.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 115.0, 123.0, 123.0, 128.0, 116.0, 123.0, 121.0, 160.0, 131.0], "value": 126.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 152.0, 150.0, 154.0, 153.0, 154.0, 151.0, 152.0, 149.0, 155.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 61.0, 60.0, 61.0, 62.0, 65.0, 61.0, 60.0, 61.0], "value": 61.5, "unit": "score"}], "name": "kraken", "value": 1460.1999999999998, "alertThreshold": 2.0}]} 14:08:47 INFO - Return code: 0 14:08:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:08:47 INFO - # TBPL SUCCESS # 14:08:47 INFO - Running post-action listener: _resource_record_post_action 14:08:47 INFO - [mozharness: 2016-08-30 21:08:47.419102Z] Finished run-tests step (success) 14:08:47 INFO - Running post-run listener: _resource_record_post_run 14:08:47 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 270336; Write bytes: 329641984; Read time: 244; Write time: 239352 14:08:47 INFO - TinderboxPrint: CPU usage
12.9% 14:08:47 INFO - TinderboxPrint: I/O read bytes / time
270,336 / 244 14:08:47 INFO - TinderboxPrint: I/O write bytes / time
329,641,984 / 239,352 14:08:47 INFO - TinderboxPrint: CPU idle
8,260.0 (87.0%) 14:08:47 INFO - TinderboxPrint: CPU user
1,186.0 (12.5%) 14:08:47 INFO - TinderboxPrint: Swap in / out
0 / 0 14:08:47 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 77217792; Read time: 20; Write time: 61996 14:08:47 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 266240; Write bytes: 249757696; Read time: 224; Write time: 177072 14:08:48 INFO - Running post-run listener: _upload_blobber_files 14:08:48 INFO - Blob upload gear active. 14:08:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:08:48 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 14:08:48 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'] 14:08:48 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 14:08:48 INFO - (blobuploader) - INFO - Open directory for files ... 14:08:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:08:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:08:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:08:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:08:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:08:52 INFO - (blobuploader) - INFO - Done attempting. 14:08:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:08:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:08:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:08:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:08:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:08:52 INFO - (blobuploader) - INFO - Done attempting. 14:08:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:08:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:08:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:08:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:08:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:08:53 INFO - (blobuploader) - INFO - Done attempting. 14:08:53 INFO - (blobuploader) - INFO - Iteration through files over. 14:08:53 INFO - Return code: 0 14:08:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:08:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:08:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e14b2997867fed6ff5fd5647f6e32842873e07bbd7b8b8763cc209a338246dbd8e709a554d9bf4b67e382831ac789f9074353dc52566a2d23e16f761941a909", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d6a07541c9a7794272a793e46d53ade06847eb7566809355e2a9efa84daf20c6885e9aee0376c5f478c9b547809d084d15401e3f255fca9abca751281524e20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7fb4db7b4f7ea55a5fe9bb90483133390d7011f08120650b97b83c9ec6a22e1648dcb1336994833e3b1cf993fc1784c28102de168561ec56c39ed6bf53da005b"} 14:08:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:08:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:08:53 INFO - Contents: 14:08:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e14b2997867fed6ff5fd5647f6e32842873e07bbd7b8b8763cc209a338246dbd8e709a554d9bf4b67e382831ac789f9074353dc52566a2d23e16f761941a909", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d6a07541c9a7794272a793e46d53ade06847eb7566809355e2a9efa84daf20c6885e9aee0376c5f478c9b547809d084d15401e3f255fca9abca751281524e20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7fb4db7b4f7ea55a5fe9bb90483133390d7011f08120650b97b83c9ec6a22e1648dcb1336994833e3b1cf993fc1784c28102de168561ec56c39ed6bf53da005b"} 14:08:53 INFO - Running post-run listener: copy_logs_to_upload_dir 14:08:53 INFO - Copying logs to upload dir... 14:08:53 INFO - mkdir: /builds/slave/test/build/upload/logs 14:08:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.079432 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-08-30 14:08:53.659242) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 14:08:53.662488) ========= 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=20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e14b2997867fed6ff5fd5647f6e32842873e07bbd7b8b8763cc209a338246dbd8e709a554d9bf4b67e382831ac789f9074353dc52566a2d23e16f761941a909", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d6a07541c9a7794272a793e46d53ade06847eb7566809355e2a9efa84daf20c6885e9aee0376c5f478c9b547809d084d15401e3f255fca9abca751281524e20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7fb4db7b4f7ea55a5fe9bb90483133390d7011f08120650b97b83c9ec6a22e1648dcb1336994833e3b1cf993fc1784c28102de168561ec56c39ed6bf53da005b"} build_url:https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011211 build_url: 'https://queue.taskcluster.net/v1/task/KUx3uDr_TxGnq0F8qAdEPQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e14b2997867fed6ff5fd5647f6e32842873e07bbd7b8b8763cc209a338246dbd8e709a554d9bf4b67e382831ac789f9074353dc52566a2d23e16f761941a909", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d6a07541c9a7794272a793e46d53ade06847eb7566809355e2a9efa84daf20c6885e9aee0376c5f478c9b547809d084d15401e3f255fca9abca751281524e20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7fb4db7b4f7ea55a5fe9bb90483133390d7011f08120650b97b83c9ec6a22e1648dcb1336994833e3b1cf993fc1784c28102de168561ec56c39ed6bf53da005b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 14:08:53.687711) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 14:08:53.688058) ========= 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=20e666a3d4cfbb71505a330e000001b4-1472589655.37548-835975430 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004381 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 14:08:53.773305) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 14:08:53.773662) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 14:08:53.773988) ========= ========= Total master_lag: 0.18 =========