builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-027 starttime: 1473988737.74 results: success (0) buildid: 20160915153318 builduid: a39c3717a04d4cbe8d5a0c2a859fb33b revision: 2d4867f1d03306471fec3f679896365845985f69 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-15 18:18:57.739165) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-15 18:18:57.739609) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-15 18:18:57.739903) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-15 18:18:57.801441) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-15 18:18:57.801754) ========= 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=1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-15 18:18:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.3M=0s 2016-09-15 18:18:57 (43.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.161902 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-15 18:18:57.992356) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-15 18:18:57.992665) ========= 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=1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016808 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-15 18:18:58.041563) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-15 18:18:58.041925) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2d4867f1d03306471fec3f679896365845985f69 --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 2d4867f1d03306471fec3f679896365845985f69 --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=1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-15 18:18:58,097 truncating revision to first 12 chars 2016-09-15 18:18:58,097 Setting DEBUG logging. 2016-09-15 18:18:58,097 attempt 1/10 2016-09-15 18:18:58,097 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2d4867f1d033?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-15 18:18:58,756 unpacking tar archive at: mozilla-aurora-2d4867f1d033/testing/mozharness/ program finished with exit code 0 elapsedTime=1.060697 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-15 18:18:59.120443) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-15 18:18:59.121028) ========= script_repo_revision: 2d4867f1d03306471fec3f679896365845985f69 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-15 18:18:59.121425) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-15 18:18:59.121699) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-15 18:18:59.132992) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 6 secs) (at 2016-09-15 18:18:59.133516) ========= /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=1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:18:59 INFO - MultiFileLogger online at 20160915 18:18:59 in /builds/slave/test 18:18:59 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 18:18:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:18:59 INFO - {'append_to_log': False, 18:18:59 INFO - 'base_work_dir': '/builds/slave/test', 18:18:59 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:18:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:18:59 INFO - 'branch': 'Mozilla-Aurora', 18:18:59 INFO - 'buildbot_json_path': 'buildprops.json', 18:18:59 INFO - 'config_files': ('talos/linux_config.py',), 18:18:59 INFO - 'default_actions': ('clobber', 18:18:59 INFO - 'read-buildbot-config', 18:18:59 INFO - 'download-and-extract', 18:18:59 INFO - 'populate-webroot', 18:18:59 INFO - 'create-virtualenv', 18:18:59 INFO - 'install', 18:18:59 INFO - 'run-tests'), 18:18:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:18:59 INFO - 'download_minidump_stackwalk': True, 18:18:59 INFO - 'download_symbols': 'ondemand', 18:18:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:18:59 INFO - 'tooltool.py': '/tools/tooltool.py', 18:18:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:18:59 INFO - '/tools/misc-python/virtualenv.py')}, 18:18:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:18:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:18:59 INFO - 'installer_path': 'installer.exe', 18:18:59 INFO - 'log_level': 'info', 18:18:59 INFO - 'log_name': 'talos', 18:18:59 INFO - 'log_to_console': True, 18:18:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:18:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:18:59 INFO - 'opt_config_files': (), 18:18:59 INFO - 'pip_index': False, 18:18:59 INFO - 'sps_profile': False, 18:18:59 INFO - 'sps_profile_interval': 0, 18:18:59 INFO - 'suite': 'dromaeojs', 18:18:59 INFO - 'system_bits': '32', 18:18:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:18:59 INFO - 'title': 'talos-linux64-ix-027', 18:18:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:18:59 INFO - 'use_talos_json': True, 18:18:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:18:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:18:59 INFO - 'work_dir': 'build'} 18:18:59 INFO - [mozharness: 2016-09-16 01:18:59.369293Z] Running clobber step. 18:18:59 INFO - Running pre-action listener: _resource_record_pre_action 18:18:59 INFO - Running main action method: clobber 18:18:59 INFO - rmtree: /builds/slave/test/build 18:18:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:18:59 INFO - Running post-action listener: _resource_record_post_action 18:18:59 INFO - [mozharness: 2016-09-16 01:18:59.781890Z] Finished clobber step (success) 18:18:59 INFO - [mozharness: 2016-09-16 01:18:59.781989Z] Running read-buildbot-config step. 18:18:59 INFO - Running pre-action listener: _resource_record_pre_action 18:18:59 INFO - Running main action method: read_buildbot_config 18:18:59 INFO - Using buildbot properties: 18:18:59 INFO - { 18:18:59 INFO - "project": "", 18:18:59 INFO - "product": "firefox", 18:18:59 INFO - "script_repo_revision": "production", 18:18:59 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 18:18:59 INFO - "repository": "", 18:18:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 18:18:59 INFO - "buildid": "20160915153318", 18:18:59 INFO - "pgo_build": "True", 18:18:59 INFO - "basedir": "/builds/slave/test", 18:18:59 INFO - "buildnumber": 520, 18:18:59 INFO - "slavename": "talos-linux64-ix-027", 18:18:59 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:18:59 INFO - "platform": "ubuntu64_hw", 18:18:59 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 18:18:59 INFO - "branch": "mozilla-aurora", 18:18:59 INFO - "repo_path": "releases/mozilla-aurora", 18:18:59 INFO - "revision": "2d4867f1d03306471fec3f679896365845985f69", 18:18:59 INFO - "stage_platform": "linux64-pgo", 18:18:59 INFO - "builduid": "a39c3717a04d4cbe8d5a0c2a859fb33b", 18:18:59 INFO - "slavebuilddir": "test" 18:18:59 INFO - } 18:18:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 18:18:59 INFO - Running post-action listener: _resource_record_post_action 18:18:59 INFO - [mozharness: 2016-09-16 01:18:59.784584Z] Finished read-buildbot-config step (success) 18:18:59 INFO - [mozharness: 2016-09-16 01:18:59.784674Z] Running download-and-extract step. 18:18:59 INFO - Running pre-action listener: _resource_record_pre_action 18:18:59 INFO - Running main action method: download_and_extract 18:18:59 INFO - mkdir: /builds/slave/test/build/tests 18:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:59 INFO - https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:18:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 18:18:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 18:18:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:18:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:18:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 18:18:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:18:59 INFO - retry: Failed, sleeping 30 seconds before retrying 18:19:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:19:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 18:19:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:19:29 INFO - retry: Failed, sleeping 60 seconds before retrying 18:20:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:20:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 18:20:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:20:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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! 18:20:29 INFO - Caught exception: HTTP Error 404: Not Found 18:20:29 INFO - Caught exception: HTTP Error 404: Not Found 18:20:29 INFO - Caught exception: HTTP Error 404: Not Found 18:20:29 INFO - trying https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 18:20:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:20:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:20:30 INFO - Downloaded 1361 bytes. 18:20:30 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 18:20:30 INFO - Using the following test package requirements: 18:20:30 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 18:20:30 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 18:20:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 18:20:30 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 18:20:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 18:20:30 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 18:20:30 INFO - u'jsshell-linux-x86_64.zip'], 18:20:30 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 18:20:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 18:20:30 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 18:20:30 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 18:20:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 18:20:30 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 18:20:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 18:20:30 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 18:20:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 18:20:30 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 18:20:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 18:20:30 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:20:30 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')]} 18:20:30 INFO - https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:20:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:20:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:20:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:20:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:20:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:20:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:20:30 INFO - retry: Failed, sleeping 30 seconds before retrying 18:21:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:21:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:21:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:21:00 INFO - retry: Failed, sleeping 60 seconds before retrying 18:22:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:22:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:22:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:22:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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! 18:22:00 INFO - Caught exception: HTTP Error 404: Not Found 18:22:00 INFO - Caught exception: HTTP Error 404: Not Found 18:22:00 INFO - Caught exception: HTTP Error 404: Not Found 18:22:00 INFO - trying https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:22:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:22:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:22:01 INFO - Downloaded 21771324 bytes. 18:22:01 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'] 18:22:01 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 18:22:02 INFO - Return code: 0 18:22:02 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 18:22:02 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')]} 18:22:02 INFO - https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:22:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:22:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:22:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:22:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:22:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:22:02 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:22:02 INFO - retry: Failed, sleeping 30 seconds before retrying 18:22:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:22:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:22:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:22:32 INFO - retry: Failed, sleeping 60 seconds before retrying 18:23:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:23:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:23:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:23:32 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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! 18:23:32 INFO - Caught exception: HTTP Error 404: Not Found 18:23:32 INFO - Caught exception: HTTP Error 404: Not Found 18:23:32 INFO - Caught exception: HTTP Error 404: Not Found 18:23:32 INFO - trying https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:23:32 INFO - Downloading https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:23:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:23:33 INFO - Downloaded 21771324 bytes. 18:23:33 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'] 18:23:33 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 18:23:35 INFO - Return code: 0 18:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:35 INFO - https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:23:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 18:23:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 18:23:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:23:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:23:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 18:23:35 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:23:35 INFO - retry: Failed, sleeping 30 seconds before retrying 18:24:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:24:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 18:24:05 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:24:05 INFO - retry: Failed, sleeping 60 seconds before retrying 18:25:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:25:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 18:25:05 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:25:05 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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! 18:25:05 INFO - Caught exception: HTTP Error 404: Not Found 18:25:05 INFO - Caught exception: HTTP Error 404: Not Found 18:25:05 INFO - Caught exception: HTTP Error 404: Not Found 18:25:05 INFO - trying https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 18:25:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:25:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:25:05 INFO - Downloaded 14413740 bytes. 18:25:05 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'] 18:25:05 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 18:25:06 INFO - Return code: 0 18:25:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:25:06 INFO - https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:25:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 18:25:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 18:25:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:25:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:25:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 18:25:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:25:06 INFO - retry: Failed, sleeping 30 seconds before retrying 18:25:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:25:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 18:25:36 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:25:36 INFO - retry: Failed, sleeping 60 seconds before retrying 18:26:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:26:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 18:26:36 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:26:36 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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! 18:26:36 INFO - Caught exception: HTTP Error 404: Not Found 18:26:36 INFO - Caught exception: HTTP Error 404: Not Found 18:26:36 INFO - Caught exception: HTTP Error 404: Not Found 18:26:36 INFO - trying https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 18:26:36 INFO - Downloading https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:26:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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 18:26:37 INFO - Downloaded 63901921 bytes. 18:26:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 18:26:37 INFO - mkdir: /builds/slave/test/properties 18:26:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:26:37 INFO - Writing to file /builds/slave/test/properties/build_url 18:26:37 INFO - Contents: 18:26:37 INFO - build_url:https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 18:26:38 INFO - Running post-action listener: _resource_record_post_action 18:26:38 INFO - Running post-action listener: set_extra_try_arguments 18:26:38 INFO - [mozharness: 2016-09-16 01:26:38.067663Z] Finished download-and-extract step (success) 18:26:38 INFO - [mozharness: 2016-09-16 01:26:38.067907Z] Running populate-webroot step. 18:26:38 INFO - Running pre-action listener: _resource_record_pre_action 18:26:38 INFO - Running main action method: populate_webroot 18:26:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:26:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:26:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:26:38 INFO - '--tppagecycles', 18:26:38 INFO - '7'], 18:26:38 INFO - 'tests': ['tsvgm']}}, 18:26:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:26:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:26:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:26:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:26:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:26:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:26:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:38 INFO - 'talos_options': ['--disable-e10s'], 18:26:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:26:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:26:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:38 INFO - 'talos_options': ['--disable-e10s'], 18:26:38 INFO - 'tests': ['damp', 'tps']}, 18:26:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:38 INFO - 'tests': ['damp', 'tps']}, 18:26:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:26:38 INFO - 'tests': ['dromaeo_dom']}, 18:26:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:26:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:26:38 INFO - 'tests': ['basic_compositor_video']}, 18:26:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:26:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:26:38 INFO - 'tests': ['a11yr', 18:26:38 INFO - 'ts_paint', 18:26:38 INFO - 'tpaint', 18:26:38 INFO - 'sessionrestore', 18:26:38 INFO - 'sessionrestore_no_auto_restore', 18:26:38 INFO - 'tabpaint']}, 18:26:38 INFO - 'other-e10s': {'tests': ['a11yr', 18:26:38 INFO - 'ts_paint', 18:26:38 INFO - 'tpaint', 18:26:38 INFO - 'sessionrestore', 18:26:38 INFO - 'sessionrestore_no_auto_restore', 18:26:38 INFO - 'tabpaint']}, 18:26:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:26:38 INFO - 'ts_paint', 18:26:38 INFO - 'tpaint', 18:26:38 INFO - 'sessionrestore', 18:26:38 INFO - 'sessionrestore_no_auto_restore', 18:26:38 INFO - 'tabpaint']}, 18:26:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:26:38 INFO - 'ts_paint', 18:26:38 INFO - 'tpaint', 18:26:38 INFO - 'sessionrestore', 18:26:38 INFO - 'sessionrestore_no_auto_restore', 18:26:38 INFO - 'tabpaint']}, 18:26:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:26:38 INFO - 'tests': ['a11yr', 18:26:38 INFO - 'ts_paint', 18:26:38 INFO - 'tpaint', 18:26:38 INFO - 'sessionrestore', 18:26:38 INFO - 'sessionrestore_no_auto_restore', 18:26:38 INFO - 'tabpaint']}, 18:26:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:26:38 INFO - 'tests': ['a11yr', 18:26:38 INFO - 'ts_paint', 18:26:38 INFO - 'tpaint', 18:26:38 INFO - 'sessionrestore', 18:26:38 INFO - 'sessionrestore_no_auto_restore', 18:26:38 INFO - 'tabpaint']}, 18:26:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:26:38 INFO - 'tests': ['tsvgx', 18:26:38 INFO - 'tsvgr_opacity', 18:26:38 INFO - 'tart', 18:26:38 INFO - 'tscrollx', 18:26:38 INFO - 'cart']}, 18:26:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:26:38 INFO - 'tsvgr_opacity', 18:26:38 INFO - 'tart', 18:26:38 INFO - 'tscrollx', 18:26:38 INFO - 'cart']}, 18:26:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:38 INFO - 'talos_options': ['--disable-e10s'], 18:26:38 INFO - 'tests': ['tp5o']}, 18:26:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:38 INFO - 'tests': ['tp5o']}, 18:26:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:38 INFO - 'talos_options': ['--disable-e10s', 18:26:38 INFO - '--xperf_path', 18:26:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:26:38 INFO - 'tests': ['tp5n']}, 18:26:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:38 INFO - 'talos_options': ['--xperf_path', 18:26:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:26:38 INFO - 'tests': ['tp5n']}}, 18:26:38 INFO - 'talos.zip': {'path': '', 18:26:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:26:38 INFO - Running post-action listener: _resource_record_post_action 18:26:38 INFO - [mozharness: 2016-09-16 01:26:38.088642Z] Finished populate-webroot step (success) 18:26:38 INFO - [mozharness: 2016-09-16 01:26:38.088731Z] Running create-virtualenv step. 18:26:38 INFO - Running pre-action listener: _resource_record_pre_action 18:26:38 INFO - Running main action method: create_virtualenv 18:26:38 INFO - Creating virtualenv /builds/slave/test/build/venv 18:26:38 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 18:26:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:26:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:26:38 INFO - Using real prefix '/usr' 18:26:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:26:38 INFO - Installing distribute.............................................................................................................................................................................................done. 18:26:40 INFO - Installing pip.................done. 18:26:40 INFO - Return code: 0 18:26:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:40 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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e3b0>, '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 0x1f6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f740>, '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': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', '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 18:26:40 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 18:26:40 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 18:26:40 INFO - Using env: {'DISPLAY': ':0', 18:26:40 INFO - 'HOME': '/home/cltbld', 18:26:40 INFO - 'LANG': 'en_US.UTF-8', 18:26:40 INFO - 'LANGUAGE': 'en_US:en', 18:26:40 INFO - 'LOGNAME': 'cltbld', 18:26:40 INFO - 'MAIL': '/var/mail/cltbld', 18:26:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:40 INFO - 'MOZ_NO_REMOTE': '1', 18:26:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:40 INFO - 'NO_EM_RESTART': '1', 18:26:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:40 INFO - 'PWD': '/builds/slave/test', 18:26:40 INFO - 'SHELL': '/bin/bash', 18:26:40 INFO - 'SHLVL': '1', 18:26:40 INFO - 'TERM': 'linux', 18:26:40 INFO - 'TMOUT': '86400', 18:26:40 INFO - 'USER': 'cltbld', 18:26:40 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', 18:26:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:40 INFO - '_': '/tools/buildbot/bin/python'} 18:26:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:41 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 18:26:41 INFO - Cleaning up... 18:26:41 INFO - Return code: 0 18:26:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:26: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')]} 18:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26: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')]} 18:26:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26: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', '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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e3b0>, '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 0x1f6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f740>, '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': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', '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 18:26: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', 'psutil>=3.1.1'] in /builds/slave/test/build 18:26: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 psutil>=3.1.1 18:26:41 INFO - Using env: {'DISPLAY': ':0', 18:26:41 INFO - 'HOME': '/home/cltbld', 18:26:41 INFO - 'LANG': 'en_US.UTF-8', 18:26:41 INFO - 'LANGUAGE': 'en_US:en', 18:26:41 INFO - 'LOGNAME': 'cltbld', 18:26:41 INFO - 'MAIL': '/var/mail/cltbld', 18:26:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:41 INFO - 'MOZ_NO_REMOTE': '1', 18:26:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:41 INFO - 'NO_EM_RESTART': '1', 18:26:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:41 INFO - 'PWD': '/builds/slave/test', 18:26:41 INFO - 'SHELL': '/bin/bash', 18:26:41 INFO - 'SHLVL': '1', 18:26:41 INFO - 'TERM': 'linux', 18:26:41 INFO - 'TMOUT': '86400', 18:26:41 INFO - 'USER': 'cltbld', 18:26:41 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', 18:26:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:41 INFO - '_': '/tools/buildbot/bin/python'} 18:26:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:41 INFO - Downloading/unpacking psutil>=3.1.1 18:26: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 18:26: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 18:26: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 18:26: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 18:26: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 18:26: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 18:26:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:26:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:26:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:26:43 INFO - Installing collected packages: psutil 18:26:43 INFO - Running setup.py install for psutil 18:26:43 INFO - building 'psutil._psutil_linux' extension 18:26:43 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 18:26:44 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 18:26:44 INFO - building 'psutil._psutil_posix' extension 18:26:44 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 18:26:44 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 18:26:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:26:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:26:44 INFO - Successfully installed psutil 18:26:44 INFO - Cleaning up... 18:26:44 INFO - Return code: 0 18:26:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:26:44 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')]} 18:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:44 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')]} 18:26:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:44 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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e3b0>, '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 0x1f6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f740>, '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': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', '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 18:26:44 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 18:26:44 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 18:26:44 INFO - Using env: {'DISPLAY': ':0', 18:26:44 INFO - 'HOME': '/home/cltbld', 18:26:44 INFO - 'LANG': 'en_US.UTF-8', 18:26:44 INFO - 'LANGUAGE': 'en_US:en', 18:26:44 INFO - 'LOGNAME': 'cltbld', 18:26:44 INFO - 'MAIL': '/var/mail/cltbld', 18:26:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:44 INFO - 'MOZ_NO_REMOTE': '1', 18:26:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:44 INFO - 'NO_EM_RESTART': '1', 18:26:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:44 INFO - 'PWD': '/builds/slave/test', 18:26:44 INFO - 'SHELL': '/bin/bash', 18:26:44 INFO - 'SHLVL': '1', 18:26:44 INFO - 'TERM': 'linux', 18:26:44 INFO - 'TMOUT': '86400', 18:26:44 INFO - 'USER': 'cltbld', 18:26:44 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', 18:26:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:44 INFO - '_': '/tools/buildbot/bin/python'} 18:26:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:26: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 18:26: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 18:26: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 18:26: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 18:26: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 18:26: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 18:26:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:26:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:26:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:26:46 INFO - Installing collected packages: mozsystemmonitor 18:26:46 INFO - Running setup.py install for mozsystemmonitor 18:26:47 INFO - Successfully installed mozsystemmonitor 18:26:47 INFO - Cleaning up... 18:26:47 INFO - Return code: 0 18:26:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:26:47 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')]} 18:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:47 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')]} 18:26:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:47 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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e3b0>, '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 0x1f6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f740>, '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': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', '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 18:26:47 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 18:26:47 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 18:26:47 INFO - Using env: {'DISPLAY': ':0', 18:26:47 INFO - 'HOME': '/home/cltbld', 18:26:47 INFO - 'LANG': 'en_US.UTF-8', 18:26:47 INFO - 'LANGUAGE': 'en_US:en', 18:26:47 INFO - 'LOGNAME': 'cltbld', 18:26:47 INFO - 'MAIL': '/var/mail/cltbld', 18:26:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:47 INFO - 'MOZ_NO_REMOTE': '1', 18:26:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:47 INFO - 'NO_EM_RESTART': '1', 18:26:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:47 INFO - 'PWD': '/builds/slave/test', 18:26:47 INFO - 'SHELL': '/bin/bash', 18:26:47 INFO - 'SHLVL': '1', 18:26:47 INFO - 'TERM': 'linux', 18:26:47 INFO - 'TMOUT': '86400', 18:26:47 INFO - 'USER': 'cltbld', 18:26:47 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', 18:26:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:47 INFO - '_': '/tools/buildbot/bin/python'} 18:26:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:47 INFO - Downloading/unpacking jsonschema==2.5.1 18:26:47 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 18:26:47 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 18:26:47 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 18:26:47 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 18:26:47 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 18:26:47 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 18:26:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:26:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:26: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 18:26: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 18:26: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 18:26: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 18:26: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 18:26: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 18:26:49 INFO - Downloading functools32-3.2.3-2.tar.gz 18:26:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:26:49 INFO - warning: no files found matching '*.txt' 18:26:49 INFO - no previously-included directories found matching 'build' 18:26:49 INFO - no previously-included directories found matching 'dist' 18:26:49 INFO - no previously-included directories found matching '.git*' 18:26:49 INFO - Installing collected packages: jsonschema, functools32 18:26:49 INFO - Running setup.py install for functools32 18:26:49 INFO - warning: no files found matching '*.txt' 18:26:49 INFO - no previously-included directories found matching 'build' 18:26:49 INFO - no previously-included directories found matching 'dist' 18:26:49 INFO - no previously-included directories found matching '.git*' 18:26:49 INFO - Successfully installed jsonschema functools32 18:26:49 INFO - Cleaning up... 18:26:49 INFO - Return code: 0 18:26:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e3b0>, '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 0x1f6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f740>, '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': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', '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 18:26:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:26:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:26:49 INFO - Using env: {'DISPLAY': ':0', 18:26:49 INFO - 'HOME': '/home/cltbld', 18:26:49 INFO - 'LANG': 'en_US.UTF-8', 18:26:49 INFO - 'LANGUAGE': 'en_US:en', 18:26:49 INFO - 'LOGNAME': 'cltbld', 18:26:49 INFO - 'MAIL': '/var/mail/cltbld', 18:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:49 INFO - 'MOZ_NO_REMOTE': '1', 18:26:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:49 INFO - 'NO_EM_RESTART': '1', 18:26:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:49 INFO - 'PWD': '/builds/slave/test', 18:26:49 INFO - 'SHELL': '/bin/bash', 18:26:49 INFO - 'SHLVL': '1', 18:26:49 INFO - 'TERM': 'linux', 18:26:49 INFO - 'TMOUT': '86400', 18:26:49 INFO - 'USER': 'cltbld', 18:26:49 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', 18:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:49 INFO - '_': '/tools/buildbot/bin/python'} 18:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:26:49 INFO - Cleaning up... 18:26:49 INFO - Return code: 0 18:26:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e3b0>, '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 0x1f6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f740>, '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': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', '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 18:26:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:26:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:26:49 INFO - Using env: {'DISPLAY': ':0', 18:26:49 INFO - 'HOME': '/home/cltbld', 18:26:49 INFO - 'LANG': 'en_US.UTF-8', 18:26:49 INFO - 'LANGUAGE': 'en_US:en', 18:26:49 INFO - 'LOGNAME': 'cltbld', 18:26:49 INFO - 'MAIL': '/var/mail/cltbld', 18:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:49 INFO - 'MOZ_NO_REMOTE': '1', 18:26:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:49 INFO - 'NO_EM_RESTART': '1', 18:26:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:49 INFO - 'PWD': '/builds/slave/test', 18:26:49 INFO - 'SHELL': '/bin/bash', 18:26:49 INFO - 'SHLVL': '1', 18:26:49 INFO - 'TERM': 'linux', 18:26:49 INFO - 'TMOUT': '86400', 18:26:49 INFO - 'USER': 'cltbld', 18:26:49 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', 18:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:49 INFO - '_': '/tools/buildbot/bin/python'} 18:26:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:50 INFO - Downloading/unpacking blobuploader==1.2.4 18:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:51 INFO - Downloading blobuploader-1.2.4.tar.gz 18:26:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:26:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:26:52 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 18:26:52 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 18:26:52 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 18:26:52 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 18:26:52 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 18:26:52 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 18:26:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:26:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:26:52 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 18:26:52 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 18:26:52 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 18:26:52 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 18:26:52 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 18:26:52 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 18:26:52 INFO - Downloading docopt-0.6.1.tar.gz 18:26:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:26:52 INFO - Installing collected packages: blobuploader, requests, docopt 18:26:52 INFO - Running setup.py install for blobuploader 18:26:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:26:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:26:52 INFO - Running setup.py install for requests 18:26:53 INFO - Running setup.py install for docopt 18:26:53 INFO - Successfully installed blobuploader requests docopt 18:26:53 INFO - Cleaning up... 18:26:53 INFO - Return code: 0 18:26:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:26: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')]} 18:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26: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')]} 18:26:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:53 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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e3b0>, '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 0x1f6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f740>, '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': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', '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 18:26:53 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 18:26:53 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 18:26:53 INFO - Using env: {'DISPLAY': ':0', 18:26:53 INFO - 'HOME': '/home/cltbld', 18:26:53 INFO - 'LANG': 'en_US.UTF-8', 18:26:53 INFO - 'LANGUAGE': 'en_US:en', 18:26:53 INFO - 'LOGNAME': 'cltbld', 18:26:53 INFO - 'MAIL': '/var/mail/cltbld', 18:26:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:53 INFO - 'MOZ_NO_REMOTE': '1', 18:26:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:53 INFO - 'NO_EM_RESTART': '1', 18:26:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:53 INFO - 'PWD': '/builds/slave/test', 18:26:53 INFO - 'SHELL': '/bin/bash', 18:26:53 INFO - 'SHLVL': '1', 18:26:53 INFO - 'TERM': 'linux', 18:26:53 INFO - 'TMOUT': '86400', 18:26:53 INFO - 'USER': 'cltbld', 18:26:53 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', 18:26:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:53 INFO - '_': '/tools/buildbot/bin/python'} 18:26:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:26:53 INFO - Running setup.py (path:/tmp/pip-XqAfhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:26:53 INFO - Running setup.py (path:/tmp/pip-TFbKKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:26:53 INFO - Running setup.py (path:/tmp/pip-IXr4PG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:26:53 INFO - Running setup.py (path:/tmp/pip-wTQIIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:26:53 INFO - Running setup.py (path:/tmp/pip-xNdRZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:26:53 INFO - Running setup.py (path:/tmp/pip-DnKrRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:26:54 INFO - Running setup.py (path:/tmp/pip-5l7OiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:26:54 INFO - Running setup.py (path:/tmp/pip-6CR1Et-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:26:54 INFO - Running setup.py (path:/tmp/pip-kja49M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:26:54 INFO - Running setup.py (path:/tmp/pip-dNQ5Oz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:26:54 INFO - Running setup.py (path:/tmp/pip-Z80Vbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:26:54 INFO - Running setup.py (path:/tmp/pip-lOcIKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:26:54 INFO - Running setup.py (path:/tmp/pip-4QYRFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:26:54 INFO - Running setup.py (path:/tmp/pip-MyCfry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:54 INFO - Running setup.py (path:/tmp/pip-weropE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:26:54 INFO - Running setup.py (path:/tmp/pip-DKlWu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:26:54 INFO - Running setup.py (path:/tmp/pip-KkCisw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:26:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:26:54 INFO - Running setup.py install for manifestparser 18:26:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:26:54 INFO - Running setup.py install for mozcrash 18:26:54 INFO - Running setup.py install for mozdebug 18:26:55 INFO - Running setup.py install for mozdevice 18:26:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:26:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:26:55 INFO - Running setup.py install for mozfile 18:26:55 INFO - Running setup.py install for mozhttpd 18:26:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:26:55 INFO - Running setup.py install for mozinfo 18:26:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:26:55 INFO - Running setup.py install for mozInstall 18:26:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:26:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:26:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:26:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:26:55 INFO - Running setup.py install for mozleak 18:26:55 INFO - Running setup.py install for mozlog 18:26:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:26:55 INFO - Running setup.py install for moznetwork 18:26:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:26:55 INFO - Running setup.py install for mozprocess 18:26:56 INFO - Running setup.py install for mozprofile 18:26:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:26:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:26:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:26:56 INFO - Running setup.py install for mozrunner 18:26:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:26:56 INFO - Running setup.py install for mozscreenshot 18:26:56 INFO - Running setup.py install for moztest 18:26:56 INFO - Running setup.py install for mozversion 18:26:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:26:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:26:56 INFO - Cleaning up... 18:26:56 INFO - Return code: 0 18:26:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:26:56 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')]} 18:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:56 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')]} 18:26:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:56 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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e3b0>, '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 0x1f6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f740>, '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': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', '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 18:26:56 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 18:26:56 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 18:26:56 INFO - Using env: {'DISPLAY': ':0', 18:26:56 INFO - 'HOME': '/home/cltbld', 18:26:56 INFO - 'LANG': 'en_US.UTF-8', 18:26:56 INFO - 'LANGUAGE': 'en_US:en', 18:26:56 INFO - 'LOGNAME': 'cltbld', 18:26:56 INFO - 'MAIL': '/var/mail/cltbld', 18:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:56 INFO - 'MOZ_NO_REMOTE': '1', 18:26:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:56 INFO - 'NO_EM_RESTART': '1', 18:26:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:56 INFO - 'PWD': '/builds/slave/test', 18:26:56 INFO - 'SHELL': '/bin/bash', 18:26:56 INFO - 'SHLVL': '1', 18:26:56 INFO - 'TERM': 'linux', 18:26:56 INFO - 'TMOUT': '86400', 18:26:56 INFO - 'USER': 'cltbld', 18:26:56 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', 18:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:56 INFO - '_': '/tools/buildbot/bin/python'} 18:26:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:26:56 INFO - Running setup.py (path:/tmp/pip-E80FF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:26:56 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)) 18:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:26:56 INFO - Running setup.py (path:/tmp/pip-0HhVug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:26:56 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)) 18:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:26:56 INFO - Running setup.py (path:/tmp/pip-WDHngw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:26:57 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)) 18:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:26:57 INFO - Running setup.py (path:/tmp/pip-H8MGGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:26:57 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)) 18:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:26:57 INFO - Running setup.py (path:/tmp/pip-3qbPlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:26:57 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)) 18:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:26:57 INFO - Running setup.py (path:/tmp/pip-Pu2AIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:26:57 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)) 18:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:26:57 INFO - Running setup.py (path:/tmp/pip-bC92lJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:26:57 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)) 18:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:26:57 INFO - Running setup.py (path:/tmp/pip-g5LZyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:26:57 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)) 18:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:26:57 INFO - Running setup.py (path:/tmp/pip-LxdpX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:26:57 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)) 18:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:26:57 INFO - Running setup.py (path:/tmp/pip-ogTKJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:26:57 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)) 18:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:26:57 INFO - Running setup.py (path:/tmp/pip-W_CqEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:26:57 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)) 18:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:26:57 INFO - Running setup.py (path:/tmp/pip-tdVDQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:26:57 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)) 18:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:26:57 INFO - Running setup.py (path:/tmp/pip-dyomOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:26:57 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)) 18:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:26:57 INFO - Running setup.py (path:/tmp/pip-L7aQIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:26:57 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)) 18:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:57 INFO - Running setup.py (path:/tmp/pip-nQTy2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:57 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)) 18:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:26:57 INFO - Running setup.py (path:/tmp/pip-FMvqF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:26:57 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)) 18:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:26:57 INFO - Running setup.py (path:/tmp/pip-uNdYGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:26:58 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)) 18:26:58 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)) 18:26:58 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)) 18:26:58 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)) 18:26:58 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)) 18:26:58 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)) 18:26:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:26:58 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 18:26:58 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 18:26:58 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 18:26:58 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 18:26:58 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 18:26:58 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 18:26:59 INFO - Downloading blessings-1.6.tar.gz 18:26:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:27:00 INFO - Installing collected packages: blessings 18:27:00 INFO - Running setup.py install for blessings 18:27:00 INFO - Successfully installed blessings 18:27:00 INFO - Cleaning up... 18:27:00 INFO - Return code: 0 18:27:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:27:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:27:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:27:00 INFO - Reading from file tmpfile_stdout 18:27:00 INFO - Current package versions: 18:27:00 INFO - argparse == 1.2.1 18:27:00 INFO - blessings == 1.6 18:27:00 INFO - blobuploader == 1.2.4 18:27:00 INFO - docopt == 0.6.1 18:27:00 INFO - functools32 == 3.2.3-2 18:27:00 INFO - jsonschema == 2.5.1 18:27:00 INFO - manifestparser == 1.1 18:27:00 INFO - mozInstall == 1.12 18:27:00 INFO - mozcrash == 0.17 18:27:00 INFO - mozdebug == 0.1 18:27:00 INFO - mozdevice == 0.48 18:27:00 INFO - mozfile == 1.2 18:27:00 INFO - mozhttpd == 0.7 18:27:00 INFO - mozinfo == 0.9 18:27:00 INFO - mozleak == 0.1 18:27:00 INFO - mozlog == 3.3 18:27:00 INFO - moznetwork == 0.27 18:27:00 INFO - mozprocess == 0.23 18:27:00 INFO - mozprofile == 0.28 18:27:00 INFO - mozrunner == 6.12 18:27:00 INFO - mozscreenshot == 0.1 18:27:00 INFO - mozsystemmonitor == 0.3 18:27:00 INFO - moztest == 0.7 18:27:00 INFO - mozversion == 1.4 18:27:00 INFO - psutil == 3.1.1 18:27:00 INFO - requests == 1.2.3 18:27:00 INFO - wsgiref == 0.1.2 18:27:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:27: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')]} 18:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27: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')]} 18:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:00 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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e3b0>, '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 0x1f6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f740>, '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': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', '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 18:27:00 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 18:27:00 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 18:27:00 INFO - Using env: {'DISPLAY': ':0', 18:27:00 INFO - 'HOME': '/home/cltbld', 18:27:00 INFO - 'LANG': 'en_US.UTF-8', 18:27:00 INFO - 'LANGUAGE': 'en_US:en', 18:27:00 INFO - 'LOGNAME': 'cltbld', 18:27:00 INFO - 'MAIL': '/var/mail/cltbld', 18:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:00 INFO - 'MOZ_NO_REMOTE': '1', 18:27:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:00 INFO - 'NO_EM_RESTART': '1', 18:27:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:00 INFO - 'PWD': '/builds/slave/test', 18:27:00 INFO - 'SHELL': '/bin/bash', 18:27:00 INFO - 'SHLVL': '1', 18:27:00 INFO - 'TERM': 'linux', 18:27:00 INFO - 'TMOUT': '86400', 18:27:00 INFO - 'USER': 'cltbld', 18:27:00 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', 18:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:00 INFO - '_': '/tools/buildbot/bin/python'} 18:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:00 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)) 18:27:00 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)) 18:27:00 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)) 18:27:00 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)) 18:27:00 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)) 18:27:00 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)) 18:27:00 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)) 18:27:00 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)) 18:27:00 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)) 18:27:00 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)) 18:27:00 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)) 18:27:00 INFO - Cleaning up... 18:27:00 INFO - Return code: 0 18:27:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:27: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')]} 18:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27: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')]} 18:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:00 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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e3b0>, '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 0x1f6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f740>, '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': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', '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 18:27:00 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 18:27:00 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 18:27:00 INFO - Using env: {'DISPLAY': ':0', 18:27:00 INFO - 'HOME': '/home/cltbld', 18:27:00 INFO - 'LANG': 'en_US.UTF-8', 18:27:00 INFO - 'LANGUAGE': 'en_US:en', 18:27:00 INFO - 'LOGNAME': 'cltbld', 18:27:00 INFO - 'MAIL': '/var/mail/cltbld', 18:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:00 INFO - 'MOZ_NO_REMOTE': '1', 18:27:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:00 INFO - 'NO_EM_RESTART': '1', 18:27:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:00 INFO - 'PWD': '/builds/slave/test', 18:27:00 INFO - 'SHELL': '/bin/bash', 18:27:00 INFO - 'SHLVL': '1', 18:27:00 INFO - 'TERM': 'linux', 18:27:00 INFO - 'TMOUT': '86400', 18:27:00 INFO - 'USER': 'cltbld', 18:27:00 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', 18:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:00 INFO - '_': '/tools/buildbot/bin/python'} 18:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:27:00 INFO - Cleaning up... 18:27:00 INFO - Return code: 0 18:27:00 INFO - Running post-action listener: _resource_record_post_action 18:27:00 INFO - Running post-action listener: _start_resource_monitoring 18:27:00 INFO - Starting resource monitoring. 18:27:00 INFO - [mozharness: 2016-09-16 01:27:00.876567Z] Finished create-virtualenv step (success) 18:27:00 INFO - [mozharness: 2016-09-16 01:27:00.877517Z] Running install step. 18:27:00 INFO - Running pre-action listener: _resource_record_pre_action 18:27:00 INFO - Running main action method: install 18:27:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:27:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:27:01 INFO - Reading from file tmpfile_stdout 18:27:01 INFO - Detecting whether we're running mozinstall >=1.0... 18:27:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:27:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:27:01 INFO - Reading from file tmpfile_stdout 18:27:01 INFO - Output received: 18:27:01 INFO - Usage: mozinstall [options] installer 18:27:01 INFO - Options: 18:27:01 INFO - -h, --help show this help message and exit 18:27:01 INFO - -d DEST, --destination=DEST 18:27:01 INFO - Directory to install application into. [default: 18:27:01 INFO - "/builds/slave/test"] 18:27:01 INFO - --app=APP Application being installed. [default: firefox] 18:27:01 INFO - mkdir: /builds/slave/test/build/application 18:27:01 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'] 18:27:01 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 18:27:17 INFO - Reading from file tmpfile_stdout 18:27:17 INFO - Output received: 18:27:17 INFO - /builds/slave/test/build/application/firefox/firefox 18:27:17 INFO - Running post-action listener: _resource_record_post_action 18:27:17 INFO - [mozharness: 2016-09-16 01:27:17.909475Z] Finished install step (success) 18:27:17 INFO - [mozharness: 2016-09-16 01:27:17.909749Z] Running run-tests step. 18:27:17 INFO - Running pre-action listener: _resource_record_pre_action 18:27:17 INFO - Running main action method: run_tests 18:27:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:27:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:27:17 INFO - Python 2.7.3 18:27:17 INFO - Return code: 0 18:27:17 INFO - grabbing minidump binary from tooltool 18:27:17 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')]} 18:27:17 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 0x1f6e3b0>, '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 0x1f6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:27:17 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 18:27:17 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 18:27:17 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 18:27:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:27:18 INFO - Return code: 0 18:27:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:27:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:27:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:27:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:27:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:27:18 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/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 18:27:18 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/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --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 18:27:18 INFO - Using env: {'DISPLAY': ':0', 18:27:18 INFO - 'HOME': '/home/cltbld', 18:27:18 INFO - 'LANG': 'en_US.UTF-8', 18:27:18 INFO - 'LANGUAGE': 'en_US:en', 18:27:18 INFO - 'LOGNAME': 'cltbld', 18:27:18 INFO - 'MAIL': '/var/mail/cltbld', 18:27:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:27:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:27:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:18 INFO - 'MOZ_NO_REMOTE': '1', 18:27:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:27:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:18 INFO - 'NO_EM_RESTART': '1', 18:27:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:18 INFO - 'PWD': '/builds/slave/test', 18:27:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:27:18 INFO - 'SHELL': '/bin/bash', 18:27:18 INFO - 'SHLVL': '1', 18:27:18 INFO - 'TERM': 'linux', 18:27:18 INFO - 'TMOUT': '86400', 18:27:18 INFO - 'USER': 'cltbld', 18:27:18 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960', 18:27:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:18 INFO - '_': '/tools/buildbot/bin/python'} 18:27:18 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/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 18:27:18 INFO - mozversion application_buildid: 20160915153318 18:27:18 INFO - mozversion application_changeset: 2d4867f1d03306471fec3f679896365845985f69 18:27:18 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:27:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:27:18 INFO - mozversion application_name: Firefox 18:27:18 INFO - mozversion application_remotingname: firefox-dev 18:27:18 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:27:18 INFO - mozversion application_vendor: Mozilla 18:27:18 INFO - mozversion application_version: 50.0a2 18:27:18 INFO - mozversion platform_buildid: 20160915153318 18:27:18 INFO - mozversion platform_changeset: 2d4867f1d03306471fec3f679896365845985f69 18:27:18 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:27:18 INFO - mozversion platform_version: 50.0a2 18:27:18 INFO - using testdate: 1473989238 18:27:18 INFO - actual date: 1473989238 18:27:18 INFO - starting webserver on 'localhost:40484' 18:27:18 INFO - SUITE-START | Running 2 tests 18:27:18 INFO - TEST-START | dromaeo_css 18:27:18 INFO - operating with platform_type : linux_ 18:27:18 INFO - Initialising browser for dromaeo_css test... 18:27:18 INFO - TEST-INFO | started process 3319 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWBqNCU/profile http://localhost:40484/getInfo.html) 18:27:24 INFO - PROCESS | 3319 | __metrics Screen width/height:1600/1200 18:27:24 INFO - PROCESS | 3319 | colorDepth:24 18:27:24 INFO - PROCESS | 3319 | Browser inner width/height: 1024/697 18:27:24 INFO - PROCESS | 3319 | __metrics 18:27:25 INFO - PROCESS | 3319 | 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" 18:27:25 INFO - PROCESS | 3319 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:27:26 INFO - TEST-INFO | 3319: exit 0 18:27:26 INFO - Browser initialized. 18:27:26 INFO - Running cycle 1/1 for dromaeo_css test... 18:27:26 INFO - TEST-INFO | started process 3409 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWBqNCU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:27:38 INFO - PROCESS | 3409 | RSS: Main: 146264064 18:27:38 INFO - PROCESS | 3409 | 18:28:29 INFO - PROCESS | 3409 | 1473989309179 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] 18:30:38 INFO - PROCESS | 3409 | Cycle 1(1): loaded http://localhost:40484/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40484/tests/dromaeo/cssquery-ext.html) 18:30:39 INFO - PROCESS | 3409 | RSS: Main: 237588480 18:30:39 INFO - PROCESS | 3409 | 18:33:41 INFO - PROCESS | 3409 | Cycle 1(1): loaded http://localhost:40484/tests/dromaeo/cssquery-ext.html (next: http://localhost:40484/tests/dromaeo/cssquery-jquery.html) 18:33:42 INFO - PROCESS | 3409 | RSS: Main: 236589056 18:33:42 INFO - PROCESS | 3409 | 18:36:44 INFO - PROCESS | 3409 | Cycle 1(1): loaded http://localhost:40484/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40484/tests/dromaeo/cssquery-mootools.html) 18:36:44 INFO - PROCESS | 3409 | RSS: Main: 235028480 18:36:44 INFO - PROCESS | 3409 | 18:39:46 INFO - PROCESS | 3409 | Cycle 1(1): loaded http://localhost:40484/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40484/tests/dromaeo/cssquery-prototype.html) 18:39:47 INFO - PROCESS | 3409 | RSS: Main: 175362048 18:39:47 INFO - PROCESS | 3409 | 18:42:50 INFO - PROCESS | 3409 | Cycle 1(1): loaded http://localhost:40484/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40484/tests/dromaeo/cssquery-yui.html) 18:42:50 INFO - PROCESS | 3409 | RSS: Main: 175210496 18:42:50 INFO - PROCESS | 3409 | 18:45:53 INFO - PROCESS | 3409 | Cycle 1(1): loaded http://localhost:40484/tests/dromaeo/cssquery-yui.html (next: http://localhost:40484/tests/dromaeo/cssquery-dojo.html) 18:45:53 INFO - PROCESS | 3409 | RSS: Main: 266723328 18:45:53 INFO - PROCESS | 3409 | 18:45:53 INFO - PROCESS | 3409 | __start_tp_report 18:45:53 INFO - PROCESS | 3409 | _x_x_mozilla_page_load 18:45:53 INFO - PROCESS | 3409 | _x_x_mozilla_page_load_details 18:45:53 INFO - PROCESS | 3409 | |i|pagename|runs| 18:45:53 INFO - PROCESS | 3409 | |0;dojo.html;3260.413125637385;3263.8204898730573;3287.4411350070486;3304.9834750826244;3334.4998250262456;5646.520045796107;5745.482906538421;5752.309722833261;5757.856053598652;5780.3930587288305;5681.318241810988;5764.048931926238;5774.249347584808;5774.769009239636;5777.566682498808;5041.697498150104;5117.565006974393;5188.11801993662;5243.134882744343;5265.473452654742;5122.590192784568;5175.197844334134;5179.404368497614;5180.870478238036;5203.089459344618;5112.693238405689;5162.741862906859;5171.560417364529;5180.611454140954;5181.481851814807;5214.921776173361;5222.5038621331105;5232.424433312332;5236.502532259429;5269.394019687747;4987.6758010729545;5037.0681423936685;5054.570361519275;5131.3329267195395;5136.332276804029;5589.692566908859;5610.831675049755;5614.382417934024;5627.127795191711;5650.378458369576;6019.819405417845;6026.879462410771;6027.517798576104;6034.215551978258;6040.667763273018;5864.38423965486;6057.182280392135;6059.333473317932;6065.3934606539;6080.087986801971;5723.771049158028;5731.369549349568;5751.223584816038;5758.078707406795;5763.221965034667;6212.223472065992;6248;6270.623762574261;6272.1219029335925;6275.6548389839;5792.044312688444;5844.386339434383;5875.794347197828;5898.262717160355;6037;5952.8809423812145;5955.702214889238;5995.430434108753;6041.395860414012;6043.2748070231855;5928.496077833346;5958.19564358806;5969.04495280753;5972.134040564094;5985.46130848217;5916.2013128228;5935.495482884004;5960.731767070492;5968.343482216953;6070.332263451017;4654.953450465519;4660.6970546914345;4660.813567457331;4662.487126416091;4664.696794708333;4070.531888832763;4110.568390319033;4118.19695159447;4162.042730172077;4169.749815011049;5713.600047996601;5715.371309155989;5715.657060576295;5726.942730572724;5753.482186603226;6053.878922421527;6104.633721976696;6115.1744514490865;6124.234470691164;6125.632462052291;5558.027345214571;5572.164175373727;5611.242482264842;5630.774769009194;5698.373178950312;5223.164293713064;5230.241614965808;5232.450592687789;5232.92150617741;5262.894742105136;6196.566240363221;6235.127082208494;6244.12582238487;6254.155688981929;6262.906056409158;5904.734286957011;5921.940780592139;5923.940760592339;5955.076963070731;6065.60573562717;6160.075988601746;6163.630182189083;6165.753369865155;6184.319724830355;6186.288576813544;6018.939810601838;6027.36712645166;6031.09533569968;6033.818985430268;6036.698165091816;5895.14520394549;5980.102984552352;6014.759409623566;6128.1726966858905;6142.293636231891;5897.616654917417;5934.376890426443;5941.435563621449;5978.880422391615;5991.7902873399225;5872.265966754156;5902.911456328073;5914.260717410324;5916.585838991401;5923.111533270044;5667.376588575171;5777.94222057774;5810.5061069809535;5866.50134738552;5868.706564671664;5957.940420595912;5970.462658360769;5975.581709280309;6007.789727359522;6014.699265036643;5802.854928626818;5806;5806.477417032487;5807.883842323215;5818.44724751148 18:45:53 INFO - PROCESS | 3409 | |1;ext.html;600.0908623435496;625.8497960489483;632.3960617610182;647.656741926779;662.1557514169433;36011.45982810257;39788.20423591526;47619.28571071432;48582;48876;49471.257931130975;49976.75011624946;50036;50066.49667516628;50072.24891626628;9197.356185067032;9327.440353578773;9338.252939764803;9342.065793420637;9342.719718408458;8452.408331416804;10884.346939183635;11134.10927125832;11152.219344645879;11543.422828858565;11337.94331028348;11341;11414.71463213418;11435.599754008612;11443.427828608577;11137.276077055007;11166.888331116746;11211.551537938472;11213.551457941672;11228.494717737722;11047.226694131414;11071.16966227528;11092.55629774808;11099.72250693731;11112.61105861299;47448.28827567589;47645;47661.285080723814;47876.52123478756;47981.280280795814;3336.065901547571;3374.1227280906896;3452.171478845084;3586.7847929124073;4019.3970904364287;35964.82017589895;36325.818370908244;36346;36371.81814090939;36586.817065914765;4735.621150307967;4806.6154707623655;4821.710697358135;4831.744641897689;4891.192953162759;377960;378558;378909;380922;381555;67216.6639166801;67579.3242067573;67703.66148169326;67726.32273677299;67790.66104669445;41883;42835.78582107132;43068;70056.64971675212;70169.24576885618;16007.839921600635;16056.839431605767;16140.919295403448;16141;16154;10857;12027.579034733742;12198.57304994321;12282.508699651913;12303.077269204845;20484.897575512325;21379.358619241448;21400.892995534923;21462;21578.568428631654;7491.363234125051;7726.806829829299;7729.5748733819055;7732.30409263169;7881.290683838482;1054.7415883108688;1059.3114475590774;1074.908632766208;1096.950637221327;1144.7939370913316;35738;35764;35895;36016.27967440689;36039.81980090083;1160;1185.8338236907477;1186.0867132308247;1192.1059205595802;1240.621610408816;18557.536061598566;18558.164882580317;18695;18757.34349297768;18824.15291311894;644087;650646;650762;650915;651191;65563.67218163879;66195.33804661989;66201.33798662048;66303;66366.66816665886;1332.7534406134896;1384.7715127004133;1388.4931999819994;1434.512265829623;1457.7813328000884;34749.47875781815;36302.45546316754;36474.452883207305;36661.63790950329;36942.630573694994;50458.49541504538;50500.74749626228;50548.24177637412;50654.74672626613;50762.49237507577;444025;444650;446475;447068;447222;30803.38393232078;30994.845025774728;31389.52915706312;31598.52602210923;33228.66771332256;52800.47199527956;52882.735586321825;53084.469155307954;53469.19796202982;53792;43433.565664342954;47442.28836567523;47650.52349476599;48097.519024809306;49270;45506.31740523961;46637.766811165726;46659.53340466688;48589.75705121452;48954.75522622507;46112.308315375965;46526.76736616295;46883.53116468792;47562.76218619023;47600.2859957094;38337;40599.797001015984;40686.38970415505;40768.592314077665;41207.05311792176;30531.694683053774;30894.211397233466;31048.534271985485;31163.376732465676;31259.374812503167 18:45:53 INFO - PROCESS | 3409 | |2;jquery.html;377.752572065297;393.8463999040373;412.99174016519663;418.5793277755855;420.07164167190507;14494.5651630451;20170.193192272327;20244.493887652818;20362.083706233236;21336.57326853466;20093.799062009413;20557.794422055777;20579.27972520962;20653.483662908435;20938.26716064938;5767.394423585537;5801.883962320751;5810.215620891189;5820.476157145856;5878.441548052928;5706.543476521868;5807.680577568232;5849.9707501462435;5856.619319744224;5858.88282234355;5766.682832444214;5824.242848429698;5854.619449735775;5858.7949421770245;5866.970665146669;5605.383407825136;5793.942060579404;5817.0983497557945;5921.348651648337;5925.407459254083;5660.066089095294;5763.279590051244;5791.507721843648;5796.82609521715;5812.941870581325;13864.514741983981;14387.78418323726;14461.132332060004;14464.855351446457;14488.565343039725;2346.8005219556358;2451.3994071452607;2508.811839112074;2576.8840402181945;3023.561583570392;10584.841227381596;10816.188785841094;11902.107341949388;15360.078395296317;15502.147381893992;1933.7389452423884;2007.989960050205;2010.8491863110326;2022.312413779322;2026.523766914774;65589.67205163944;73934.63032684856;74512.25487745108;84865.57567212124;84916;9892.120497105527;10450.581976720998;10573.788524229472;11368.886311136948;11865.762684746256;14370.49703260381;14438.566842994727;14454.783178252335;14568;14570.41718331255;7599.316061554487;7687.154413014564;7903.644336004893;7986.600669966595;8045.396595255379;7432.405407567381;7475.551466912003;7504.774856754306;7620.657070431733;7640.083190017233;4257.40396344512;4314.590113939171;4409.735415875058;4476.820927162877;4499.325101234841;2048.897555122238;2092.9895350523457;2120.957580848374;2143.164165975271;2219.922302719397;911.6854685133618;993.9403635781877;1035.8756949165997;1073.9892601073889;1150.4880328254014;19677.50806229827;21020.684689729667;21060.262890799055;21163.04766285521;21274.574508509744;994.796066806303;1021.8824835144054;1028.4292217819182;1056.4031322302874;1130.8118790141102;9991;11834.763304733857;11854.466549005316;12023.519059237533;12051.27692338445;486496;486762;487046;487083;508817;56584.78546464005;80200.19799802043;96206.51896740471;99436.00563994267;99450;961.1157734883924;982.5087456271864;1005.9899401006043;1007.1870632860821;1015.441507171053;18166.455006349832;18823.34118305853;19107;19139.904300478407;19410.708839367704;43049;43052;43646.56353436425;44390.556094438645;44987;56099;57014;59980.400195997485;65842.67078664576;66925.66537167283;8845.778855528662;8906.777330566787;8952.955235223782;8954.104589540993;9214.631414743335;12332.383380831103;13878.722425551634;14092.506762263682;14290.35693393779;14340.713185736018;12176.512939482676;12188.329641869783;12489.125761196628;12595;12641.2415255085;12066.155369124077;12179.573714919936;12204.328761917825;12379.390679211645;12534.561290354794;12316;12759.234445933274;12780.424880880568;12846;12849.871501284868;8912.598933048159;8912.73261802147;9180.908190918006;9227.35408521397;9464.526773661151;8891.888513935757;12407.44166512491;12687.22378866941;12881.291528965998;13050.804237936629 18:45:53 INFO - PROCESS | 3409 | |3;mootools.html;1537.6770878115594;1548.8141423029238;1704.4630941253504;1799.6850551153545;1868.9065546722668;9403.200727938125;9875.407475551472;9878.703638890845;9887.555060022298;9890;8396.286315663176;8898.332625053115;9244.075592440753;9268.443893366402;9650.806983860319;2435.610302351624;2495.950080998379;2496.2012156110054;2499.687539057618;2503.849769013865;2420.2376251480814;2455.5702752018415;2456.1771806444863;2457.447074408262;2459.053264493165;2475.084218839023;2484.888180031894;2519.60946053362;2520.3573088862313;2522.5207210629924;2371.2886134159685;2380.523895220963;2384.201292566992;2388.056717596557;2409.204962362416;2401.5677178107935;2408.915687950931;2423.4426082001237;2436.4030812450887;2437.524682686877;9525.28560357976;9591.472469014201;9598.424094554279;9600.039996000414;9607.13535781783;5453.727313634303;5622.999999999959;5628.605997580171;5629.211910332557;5629.9437005629825;3433.1588760753525;3478.617352091268;3555;3557.804320762357;3565.3938830398633;1306.4643496166525;1312.08154291996;1320.1089264746258;1345.1727187779445;1352.648311439033;13608.251546164956;13856.93071534636;13864.653383665489;13879.306034698224;13892.097013694078;4973.2042873140845;5075.314832497639;5076.137056700296;5124.692518448905;5283.101872681576;11470.598529051444;11543.711407214887;11546.13403994687;11547.307161570332;11552.76894462106;5500.4224556420995;5523.447655234444;5533.723313834373;5536.723163841792;5538.16927460876;4625.884352891205;5064.518870707277;5196.974015129976;5291.603129765283;5316.973415132899;4381.1237752449;4412.448443944507;4457.130859482435;4500.819967201341;4514.887127821765;1371.1087792934673;1411.1674112273809;1412.4773833681397;1427.06527224668;1430.3277459593974;834.4492634861084;954.1698722111807;1042.588177669816;1062.85651437055;1067.756064185231;6402.103705481236;6618.966905165444;6638.6680665966505;6659.633720145439;6780.72877084911;1011.9392836429838;1078.1105587889865;1092.5848177692585;1095.200503632337;1126.8760436351995;4391.714538554984;4558.2251017327135;4562.475315338712;4695.483496815347;4846.684965477232;11536.826947595793;12061.216020958609;12099.33453660057;12436.626901192978;12565.685857853627;12286.75426491465;12317.07621928359;12335.013198944063;12336.50653973831;12437.62687119388;8792.296616270554;8882.422642528216;9084.591193396313;9088.727338179866;9100.908990910139;6302.810915672537;6337.144485494399;6339.080833279057;6670.199576050711;6953.20038195614;10970.286931349438;11141.610043648434;11170.329780213213;11218.551257949917;11332.263402878787;11282.605108821152;11292.096632269235;11455.198136130724;11525.308481491138;11640.650780476599;8661.480311181098;8706.695265665672;8738.645509945969;8792.164744349277;8810.911890881265;9305.627774888928;9339.112784285482;9345.953270233606;9401.435913845191;9412.67055653049;8332.750017499484;8364.958175209329;8485.57572121379;8496;8592.183742544448;8030;8090.514569125636;8136.186381362053;8161.510309381456;8299.585020749058;7374.299441553044;7385.29839665231;7440.2931721486375;7442.851142977219;7459.850802984019;5676.120201368794;5793.594448388572;5875.794347197828;5901.7639294427745;5932.16949627044;5719.599628025998;5795.304563452412;5842.912356314574;5864;5879.794207202727 18:45:53 INFO - PROCESS | 3409 | |4;prototype.html;329.2920221523724;335.36783163736357;340.2395645731789;350.7965380079554;354.62232722150907;5386.06101803351;5438.564914806806;5453.972730136349;5460.754266058027;5471.127657979563;5371.818199995994;5376.5752075699875;5380.354357477108;5402.243685883969;5410.08028635132;4087.2234275487635;4146.336586146217;4277.871663850089;4314.525402205755;4323.1353729254115;4242.363645453192;4256.382824490447;4273.230818452677;4282.593153650414;4308.138372325532;4231.70378073535;4235.110626768367;4239.071222593712;4264.402983582301;4279.935800962988;4058.959410405888;4062.573429789859;4240.957590424118;4272.33778764292;4273.465816772903;3756.549214094326;3775.3959366501504;3775.9433608495897;3791.0711875590655;3799.715021373408;5298.496642818926;5305.575553955713;5329.415233611734;5346.652467589594;5363.9080758504215;4795.736234507136;4837.903241935177;4840.1771698810935;4857.854264372075;4888.902221955576;5517.834464966058;5536.806211782608;5540.058190107691;5540.63985840919;5598.86002849932;4105.527864295615;4130.607592278729;4155.252054630166;4167.604077612622;4172.83308667656;5702.344230413514;5822.475977162076;5827.912581311284;5839.737211825478;5877.9118313225335;4271.530131685512;4292.763897985578;4293.957060429419;4294.592013758688;4302.634276086505;4324.394584758136;4334.501532323823;4351.891202719894;4353.36876152956;4357.891052723708;4731.597814185764;4740.810367585258;4764.547368000034;4818.373611430562;4829.420469543677;5598.384177740445;5599.8880022400135;5606.635568688022;5613.578981576316;5618.297712785902;1479.1125324805246;1496.6258435391153;1501.1893577468045;1503.9849601504063;1513.402206128573;962.6186422483759;966.21253678252;987.4865070163474;987.8221920054316;999.4053538144792;5320.600954928396;5398.001369746609;5433.673979561239;5455.263539422127;5460.426655201226;5776.768929242868;5781.71091445426;5783.595148339576;5817.592768506164;5832.62087964281;5196.168613021899;5387.542058924957;5444.891102177934;5470.152126420411;5495.708508500499;3026.0481989116897;3044.2389402649337;3050.8474576271096;3051.9542206866913;3064.877404903779;5831.653026529072;5934.406559344031;5953.9878220702585;5996.760129594854;6040.942835003944;5779.393163717833;5831.475167234969;5837.474627283565;5876.529877609781;5912.408759124053;5934.584579079423;5945.554083443759;5949.286085669747;5959.195508606369;6014.308354539284;5518.2826232589505;5737.713114344349;5787.565932554991;5790.160426738184;5853.882922341614;5679.233303504139;5685.431456854281;5720.427957204246;5721.396764472239;5726.828195154152;5820.563457740602;5922.703864806656;5958.314793798595;6013.9097913532205;6016.127661488972;4130.690198235085;4150.335946248586;4158.646515046134;4160.6463450605825;4179.791010449526;5715.799947002001;5743.483086522233;5779.162021506774;5782.190493330853;5863.73613187399;5607.691576963306;5721.742521586455;5758.740856661544;5771.1920331154515;5798.478136967693;5601.887962240814;5617.522510586646;5634.577406694596;5660.688662123623;5665.546756259407;5541.556675465872;5586.413426590149;5622.634528755618;5635.887282254414;5638.88722225545;5191.169412893919;5433.619646624698;5641.351244606923;5663.235463212413;5687;5525.889482210414;5534.284371844741;5544.807866308763;5555.361168455234;5579.302587176603 18:45:53 INFO - PROCESS | 3409 | |5;yui.html;820.626614890225;835.3943391041496;839.3788596438634;932.7574830544058;951.2722767083178;15416.229188540585;16257.18714064298;16280.185990700476;16353.100579468124;16447.83552164478;15941.20293985302;16067.116308603023;16226;16256.268467918942;16292.674146517062;861.1947828780095;890.6259371064136;891.7146513115806;896.3277541843618;897.5198268926126;888.5379602606673;891.2023738753811;891.4383938118976;892.098980030171;892.5180402582598;933.8085692433035;937.7327461673425;938.1556599060832;938.441627231796;938.9014153513886;928;928.5682157796657;928.5960607135918;929.0430856218072;929.0709290709291;684.3567046975827;687.0793137196131;687.6355531568277;687.656171914043;688.1501345837886;15493.612659683486;15507.612309692347;15546.222688865511;15549.067055976677;15553.066815991077;14430.711385772225;14454.710905781825;14508.782368264485;14616.780748288784;14755.926220368936;12137;12285.754284914341;12341.259524428473;12342;12803.359832008362;4079.653229475498;4094.28350038742;4105.630493255591;4230.598093181143;4237.555056719062;55236.72381638106;58261.417385826026;72547.63726181335;83878.16121838764;86422.56788716016;12756.808647870104;12932.288724120075;12941.158824676366;13330.333483325794;13429.328533573282;22978.65532017021;22996.7700322998;23003.53992920132;23044.65433018506;23079.88460057689;9065.456072635663;9117.772055698528;9131.406458580172;9253.120953509406;9254;4906.11689895815;4958.157113290749;4992.5756310714;5140.40885298188;5170.689758614495;3927.862524811675;4090;4095.28332541804;4095.836166553364;4096.631303182728;702.3187508117118;703.3599424523659;704.4998051383575;715.8675645005691;723.2405973727565;670.6043434373743;670.8054664147343;684.090160087083;726.5204964723257;740.9481336306407;10435.791284174426;10441.425721585385;10463.215258855616;10567.520502478232;10852.186086043579;809.9271065604124;816;820.2617644120339;821.2526600793369;832.92087251711;5008.424031236382;5325.520703136736;5358.758855851496;5373.274607927958;5378.408375078739;128231;131378.34310828385;132653;137285;142438;76228;92992;115396.42301788437;115513;115577.4221128889;94654.52672736593;95119;96241;96897;97240.51379742914;10316.742081448025;10484.370937743759;10503.47482625881;10695.839562406725;10776.084032857296;52078.73960630173;53369.19946200888;54054.729726351114;54254.643633909465;56004;80490.59754701189;81701;82415.58792206;82690.58654706688;84837;13912.165270083828;14120.576382708536;14251.144931304156;14459.638509037359;14750.852491474949;14618.853811462175;14683.55949321522;14867.18230497333;15034.398624054915;15054.397824086915;14070.366833492722;14310.141391516543;14425.567232983027;14513;14677.926610367307;13986.300684965914;14351.928240358731;14431.85568144305;14440.494582689555;14729.26353682333;12865.763406580732;13012;13130.868691312964;13668.931655342058;13844.446222151;11351.318920864775;11523.596674116701;11583.6524904253;11802.468888899848;12011.918927296585;11641.883581164419;11680.299182049104;11781;11983.28100313984;12030.699232518908 18:45:53 INFO - PROCESS | 3409 | __end_tp_report 18:45:53 INFO - PROCESS | 3409 | __start_cc_report 18:45:53 INFO - PROCESS | 3409 | _x_x_mozilla_cycle_collect,875 18:45:53 INFO - PROCESS | 3409 | __end_cc_report 18:45:53 INFO - PROCESS | 3409 | __startTimestamp1473990353371__endTimestamp 18:45:53 INFO - PROCESS | 3409 | 18:45:53 INFO - PROCESS | 3409 | ------- Summary: start ------- 18:45:53 INFO - PROCESS | 3409 | Number of tests: 6 18:45:53 INFO - PROCESS | 3409 | 18:45:53 INFO - PROCESS | 3409 | [#0] dojo.html Cycles:165 Average:5618.97 Median:5810.51 stddev:626.21 (10.8%) stddev-sans-first:600.18 18:45:53 INFO - PROCESS | 3409 | Values: 3260.4 3263.8 3287.4 3305.0 3334.5 5646.5 5745.5 5752.3 5757.9 5780.4 5681.3 5764.0 5774.2 5774.8 5777.6 5041.7 5117.6 5188.1 5243.1 5265.5 5122.6 5175.2 5179.4 5180.9 5203.1 5112.7 5162.7 5171.6 5180.6 5181.5 5214.9 5222.5 5232.4 5236.5 5269.4 4987.7 5037.1 5054.6 5131.3 5136.3 5589.7 5610.8 5614.4 5627.1 5650.4 6019.8 6026.9 6027.5 6034.2 6040.7 5864.4 6057.2 6059.3 6065.4 6080.1 5723.8 5731.4 5751.2 5758.1 5763.2 6212.2 6248.0 6270.6 6272.1 6275.7 5792.0 5844.4 5875.8 5898.3 6037.0 5952.9 5955.7 5995.4 6041.4 6043.3 5928.5 5958.2 5969.0 5972.1 5985.5 5916.2 5935.5 5960.7 5968.3 6070.3 4655.0 4660.7 4660.8 4662.5 4664.7 4070.5 4110.6 4118.2 4162.0 4169.7 5713.6 5715.4 5715.7 5726.9 5753.5 6053.9 6104.6 6115.2 6124.2 6125.6 5558.0 5572.2 5611.2 5630.8 5698.4 5223.2 5230.2 5232.5 5232.9 5262.9 6196.6 6235.1 6244.1 6254.2 6262.9 5904.7 5921.9 5923.9 5955.1 6065.6 6160.1 6163.6 6165.8 6184.3 6186.3 6018.9 6027.4 6031.1 6033.8 6036.7 5895.1 5980.1 6014.8 6128.2 6142.3 5897.6 5934.4 5941.4 5978.9 5991.8 5872.3 5902.9 5914.3 5916.6 5923.1 5667.4 5777.9 5810.5 5866.5 5868.7 5957.9 5970.5 5975.6 6007.8 6014.7 5802.9 5806.0 5806.5 5807.9 5818.4 18:45:53 INFO - PROCESS | 3409 | 18:45:53 INFO - PROCESS | 3409 | [#1] ext.html Cycles:180 Average:66740.01 Median:21903.80 stddev:134187.82 (612.6%) stddev-sans-first:134472.36 18:45:53 INFO - PROCESS | 3409 | Values: 600.1 625.8 632.4 647.7 662.2 36011.5 39788.2 47619.3 48582.0 48876.0 49471.3 49976.8 50036.0 50066.5 50072.2 9197.4 9327.4 9338.3 9342.1 9342.7 8452.4 10884.3 11134.1 11152.2 11543.4 11337.9 11341.0 11414.7 11435.6 11443.4 11137.3 11166.9 11211.6 11213.6 11228.5 11047.2 11071.2 11092.6 11099.7 11112.6 47448.3 47645.0 47661.3 47876.5 47981.3 3336.1 3374.1 3452.2 3586.8 4019.4 35964.8 36325.8 36346.0 36371.8 36586.8 4735.6 4806.6 4821.7 4831.7 4891.2 377960.0 378558.0 378909.0 380922.0 381555.0 67216.7 67579.3 67703.7 67726.3 67790.7 41883.0 42835.8 43068.0 70056.6 70169.2 16007.8 16056.8 16140.9 16141.0 16154.0 10857.0 12027.6 12198.6 12282.5 12303.1 20484.9 21379.4 21400.9 21462.0 21578.6 7491.4 7726.8 7729.6 7732.3 7881.3 1054.7 1059.3 1074.9 1097.0 1144.8 35738.0 35764.0 35895.0 36016.3 36039.8 1160.0 1185.8 1186.1 1192.1 1240.6 18557.5 18558.2 18695.0 18757.3 18824.2 644087.0 650646.0 650762.0 650915.0 651191.0 65563.7 66195.3 66201.3 66303.0 66366.7 1332.8 1384.8 1388.5 1434.5 1457.8 34749.5 36302.5 36474.5 36661.6 36942.6 50458.5 50500.7 50548.2 50654.7 50762.5 444025.0 444650.0 446475.0 447068.0 447222.0 30803.4 30994.8 31389.5 31598.5 33228.7 52800.5 52882.7 53084.5 53469.2 53792.0 43433.6 47442.3 47650.5 48097.5 49270.0 45506.3 46637.8 46659.5 48589.8 48954.8 46112.3 46526.8 46883.5 47562.8 47600.3 38337.0 40599.8 40686.4 40768.6 41207.1 30531.7 30894.2 31048.5 31163.4 31259.4 18:45:53 INFO - PROCESS | 3409 | 18:45:53 INFO - PROCESS | 3409 | [#2] jquery.html Cycles:180 Average:28981.84 Median:2800.22 stddev:80889.62 (2888.7%) stddev-sans-first:81088.02 18:45:53 INFO - PROCESS | 3409 | Values: 377.8 393.8 413.0 418.6 420.1 14494.6 20170.2 20244.5 20362.1 21336.6 20093.8 20557.8 20579.3 20653.5 20938.3 5767.4 5801.9 5810.2 5820.5 5878.4 5706.5 5807.7 5850.0 5856.6 5858.9 5766.7 5824.2 5854.6 5858.8 5867.0 5605.4 5793.9 5817.1 5921.3 5925.4 5660.1 5763.3 5791.5 5796.8 5812.9 13864.5 14387.8 14461.1 14464.9 14488.6 2346.8 2451.4 2508.8 2576.9 3023.6 10584.8 10816.2 11902.1 15360.1 15502.1 1933.7 2008.0 2010.8 2022.3 2026.5 65589.7 73934.6 74512.3 84865.6 84916.0 9892.1 10450.6 10573.8 11368.9 11865.8 14370.5 14438.6 14454.8 14568.0 14570.4 7599.3 7687.2 7903.6 7986.6 8045.4 7432.4 7475.6 7504.8 7620.7 7640.1 4257.4 4314.6 4409.7 4476.8 4499.3 2048.9 2093.0 2121.0 2143.2 2219.9 911.7 993.9 1035.9 1074.0 1150.5 19677.5 21020.7 21060.3 21163.0 21274.6 994.8 1021.9 1028.4 1056.4 1130.8 9991.0 11834.8 11854.5 12023.5 12051.3 486496.0 486762.0 487046.0 487083.0 508817.0 56584.8 80200.2 96206.5 99436.0 99450.0 961.1 982.5 1006.0 1007.2 1015.4 18166.5 18823.3 19107.0 19139.9 19410.7 43049.0 43052.0 43646.6 44390.6 44987.0 56099.0 57014.0 59980.4 65842.7 66925.7 8845.8 8906.8 8953.0 8954.1 9214.6 12332.4 13878.7 14092.5 14290.4 14340.7 12176.5 12188.3 12489.1 12595.0 12641.2 12066.2 12179.6 12204.3 12379.4 12534.6 12316.0 12759.2 12780.4 12846.0 12849.9 8912.6 8912.7 9180.9 9227.4 9464.5 8891.9 12407.4 12687.2 12881.3 13050.8 18:45:53 INFO - PROCESS | 3409 | 18:45:53 INFO - PROCESS | 3409 | [#3] mootools.html Cycles:180 Average:6374.18 Median:4594.18 stddev:3683.27 (80.2%) stddev-sans-first:3675.67 18:45:53 INFO - PROCESS | 3409 | Values: 1537.7 1548.8 1704.5 1799.7 1868.9 9403.2 9875.4 9878.7 9887.6 9890.0 8396.3 8898.3 9244.1 9268.4 9650.8 2435.6 2496.0 2496.2 2499.7 2503.8 2420.2 2455.6 2456.2 2457.4 2459.1 2475.1 2484.9 2519.6 2520.4 2522.5 2371.3 2380.5 2384.2 2388.1 2409.2 2401.6 2408.9 2423.4 2436.4 2437.5 9525.3 9591.5 9598.4 9600.0 9607.1 5453.7 5623.0 5628.6 5629.2 5629.9 3433.2 3478.6 3555.0 3557.8 3565.4 1306.5 1312.1 1320.1 1345.2 1352.6 13608.3 13856.9 13864.7 13879.3 13892.1 4973.2 5075.3 5076.1 5124.7 5283.1 11470.6 11543.7 11546.1 11547.3 11552.8 5500.4 5523.4 5533.7 5536.7 5538.2 4625.9 5064.5 5197.0 5291.6 5317.0 4381.1 4412.4 4457.1 4500.8 4514.9 1371.1 1411.2 1412.5 1427.1 1430.3 834.4 954.2 1042.6 1062.9 1067.8 6402.1 6619.0 6638.7 6659.6 6780.7 1011.9 1078.1 1092.6 1095.2 1126.9 4391.7 4558.2 4562.5 4695.5 4846.7 11536.8 12061.2 12099.3 12436.6 12565.7 12286.8 12317.1 12335.0 12336.5 12437.6 8792.3 8882.4 9084.6 9088.7 9100.9 6302.8 6337.1 6339.1 6670.2 6953.2 10970.3 11141.6 11170.3 11218.6 11332.3 11282.6 11292.1 11455.2 11525.3 11640.7 8661.5 8706.7 8738.6 8792.2 8810.9 9305.6 9339.1 9346.0 9401.4 9412.7 8332.8 8365.0 8485.6 8496.0 8592.2 8030.0 8090.5 8136.2 8161.5 8299.6 7374.3 7385.3 7440.3 7442.9 7459.9 5676.1 5793.6 5875.8 5901.8 5932.2 5719.6 5795.3 5842.9 5864.0 5879.8 18:45:53 INFO - PROCESS | 3409 | 18:45:53 INFO - PROCESS | 3409 | [#4] prototype.html Cycles:180 Average:4773.64 Median:5454.62 stddev:1380.28 (25.3%) stddev-sans-first:1343.24 18:45:53 INFO - PROCESS | 3409 | Values: 329.3 335.4 340.2 350.8 354.6 5386.1 5438.6 5454.0 5460.8 5471.1 5371.8 5376.6 5380.4 5402.2 5410.1 4087.2 4146.3 4277.9 4314.5 4323.1 4242.4 4256.4 4273.2 4282.6 4308.1 4231.7 4235.1 4239.1 4264.4 4279.9 4059.0 4062.6 4241.0 4272.3 4273.5 3756.5 3775.4 3775.9 3791.1 3799.7 5298.5 5305.6 5329.4 5346.7 5363.9 4795.7 4837.9 4840.2 4857.9 4888.9 5517.8 5536.8 5540.1 5540.6 5598.9 4105.5 4130.6 4155.3 4167.6 4172.8 5702.3 5822.5 5827.9 5839.7 5877.9 4271.5 4292.8 4294.0 4294.6 4302.6 4324.4 4334.5 4351.9 4353.4 4357.9 4731.6 4740.8 4764.5 4818.4 4829.4 5598.4 5599.9 5606.6 5613.6 5618.3 1479.1 1496.6 1501.2 1504.0 1513.4 962.6 966.2 987.5 987.8 999.4 5320.6 5398.0 5433.7 5455.3 5460.4 5776.8 5781.7 5783.6 5817.6 5832.6 5196.2 5387.5 5444.9 5470.2 5495.7 3026.0 3044.2 3050.8 3052.0 3064.9 5831.7 5934.4 5954.0 5996.8 6040.9 5779.4 5831.5 5837.5 5876.5 5912.4 5934.6 5945.6 5949.3 5959.2 6014.3 5518.3 5737.7 5787.6 5790.2 5853.9 5679.2 5685.4 5720.4 5721.4 5726.8 5820.6 5922.7 5958.3 6013.9 6016.1 4130.7 4150.3 4158.6 4160.6 4179.8 5715.8 5743.5 5779.2 5782.2 5863.7 5607.7 5721.7 5758.7 5771.2 5798.5 5601.9 5617.5 5634.6 5660.7 5665.5 5541.6 5586.4 5622.6 5635.9 5638.9 5191.2 5433.6 5641.4 5663.2 5687.0 5525.9 5534.3 5544.8 5555.4 5579.3 18:45:53 INFO - PROCESS | 3409 | 18:45:53 INFO - PROCESS | 3409 | [#5] yui.html Cycles:180 Average:22306.95 Median:4092.14 stddev:32906.23 (804.1%) stddev-sans-first:32959.00 18:45:53 INFO - PROCESS | 3409 | Values: 820.6 835.4 839.4 932.8 951.3 15416.2 16257.2 16280.2 16353.1 16447.8 15941.2 16067.1 16226.0 16256.3 16292.7 861.2 890.6 891.7 896.3 897.5 888.5 891.2 891.4 892.1 892.5 933.8 937.7 938.2 938.4 938.9 928.0 928.6 928.6 929.0 929.1 684.4 687.1 687.6 687.7 688.2 15493.6 15507.6 15546.2 15549.1 15553.1 14430.7 14454.7 14508.8 14616.8 14755.9 12137.0 12285.8 12341.3 12342.0 12803.4 4079.7 4094.3 4105.6 4230.6 4237.6 55236.7 58261.4 72547.6 83878.2 86422.6 12756.8 12932.3 12941.2 13330.3 13429.3 22978.7 22996.8 23003.5 23044.7 23079.9 9065.5 9117.8 9131.4 9253.1 9254.0 4906.1 4958.2 4992.6 5140.4 5170.7 3927.9 4090.0 4095.3 4095.8 4096.6 702.3 703.4 704.5 715.9 723.2 670.6 670.8 684.1 726.5 740.9 10435.8 10441.4 10463.2 10567.5 10852.2 809.9 816.0 820.3 821.3 832.9 5008.4 5325.5 5358.8 5373.3 5378.4 128231.0 131378.3 132653.0 137285.0 142438.0 76228.0 92992.0 115396.4 115513.0 115577.4 94654.5 95119.0 96241.0 96897.0 97240.5 10316.7 10484.4 10503.5 10695.8 10776.1 52078.7 53369.2 54054.7 54254.6 56004.0 80490.6 81701.0 82415.6 82690.6 84837.0 13912.2 14120.6 14251.1 14459.6 14750.9 14618.9 14683.6 14867.2 15034.4 15054.4 14070.4 14310.1 14425.6 14513.0 14677.9 13986.3 14351.9 14431.9 14440.5 14729.3 12865.8 13012.0 13130.9 13668.9 13844.4 11351.3 11523.6 11583.7 11802.5 12011.9 11641.9 11680.3 11781.0 11983.3 12030.7 18:45:53 INFO - PROCESS | 3409 | -------- Summary: end -------- 18:45:53 INFO - PROCESS | 3409 | 18:45:54 INFO - TEST-INFO | 3409: exit 0 18:45:54 INFO - TEST-OK | dromaeo_css | took 1115844ms 18:45:54 INFO - TEST-START | kraken 18:45:54 INFO - operating with platform_type : linux_ 18:45:54 INFO - Initialising browser for kraken test... 18:45:54 INFO - TEST-INFO | started process 3614 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY9r7VG/profile http://localhost:40484/getInfo.html) 18:45:58 INFO - PROCESS | 3614 | __metrics Screen width/height:1600/1200 18:45:58 INFO - PROCESS | 3614 | colorDepth:24 18:45:58 INFO - PROCESS | 3614 | Browser inner width/height: 1024/697 18:45:58 INFO - PROCESS | 3614 | __metrics 18:45:59 INFO - PROCESS | 3614 | 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" 18:45:59 INFO - PROCESS | 3614 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:46:00 INFO - TEST-INFO | 3614: exit 0 18:46:00 INFO - Browser initialized. 18:46:00 INFO - Running cycle 1/1 for kraken test... 18:46:00 INFO - TEST-INFO | started process 3701 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY9r7VG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:46:11 INFO - PROCESS | 3701 | RSS: Main: 145907712 18:46:11 INFO - PROCESS | 3701 | 18:46:14 INFO - PROCESS | 3701 | Cycle 1(1): loaded http://localhost:40484/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40484/tests/kraken/driver.html?testName=audio-beat-detection) 18:46:15 INFO - PROCESS | 3701 | RSS: Main: 200843264 18:46:15 INFO - PROCESS | 3701 | 18:46:17 INFO - PROCESS | 3701 | Cycle 1(1): loaded http://localhost:40484/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40484/tests/kraken/driver.html?testName=audio-dft) 18:46:18 INFO - PROCESS | 3701 | RSS: Main: 185417728 18:46:18 INFO - PROCESS | 3701 | 18:46:21 INFO - PROCESS | 3701 | Cycle 1(1): loaded http://localhost:40484/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40484/tests/kraken/driver.html?testName=audio-fft) 18:46:21 INFO - PROCESS | 3701 | RSS: Main: 275804160 18:46:21 INFO - PROCESS | 3701 | 18:46:23 INFO - PROCESS | 3701 | Cycle 1(1): loaded http://localhost:40484/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40484/tests/kraken/driver.html?testName=audio-oscillator) 18:46:24 INFO - PROCESS | 3701 | RSS: Main: 181075968 18:46:24 INFO - PROCESS | 3701 | 18:46:26 INFO - PROCESS | 3701 | Cycle 1(1): loaded http://localhost:40484/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40484/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:46:27 INFO - PROCESS | 3701 | RSS: Main: 180289536 18:46:27 INFO - PROCESS | 3701 | 18:46:30 INFO - PROCESS | 3701 | Cycle 1(1): loaded http://localhost:40484/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40484/tests/kraken/driver.html?testName=imaging-darkroom) 18:46:30 INFO - PROCESS | 3701 | RSS: Main: 216281088 18:46:30 INFO - PROCESS | 3701 | 18:46:34 INFO - PROCESS | 3701 | Cycle 1(1): loaded http://localhost:40484/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40484/tests/kraken/driver.html?testName=imaging-desaturate) 18:46:34 INFO - PROCESS | 3701 | RSS: Main: 214876160 18:46:34 INFO - PROCESS | 3701 | 18:46:37 INFO - PROCESS | 3701 | Cycle 1(1): loaded http://localhost:40484/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40484/tests/kraken/driver.html?testName=json-parse-financial) 18:46:38 INFO - PROCESS | 3701 | RSS: Main: 214970368 18:46:38 INFO - PROCESS | 3701 | 18:46:39 INFO - PROCESS | 3701 | Cycle 1(1): loaded http://localhost:40484/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40484/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:46:40 INFO - PROCESS | 3701 | RSS: Main: 177262592 18:46:40 INFO - PROCESS | 3701 | 18:46:41 INFO - PROCESS | 3701 | Cycle 1(1): loaded http://localhost:40484/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40484/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:46:42 INFO - PROCESS | 3701 | RSS: Main: 185724928 18:46:42 INFO - PROCESS | 3701 | 18:46:44 INFO - PROCESS | 3701 | Cycle 1(1): loaded http://localhost:40484/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40484/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:46:45 INFO - PROCESS | 3701 | RSS: Main: 186961920 18:46:45 INFO - PROCESS | 3701 | 18:46:47 INFO - PROCESS | 3701 | Cycle 1(1): loaded http://localhost:40484/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40484/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:46:48 INFO - PROCESS | 3701 | RSS: Main: 180019200 18:46:48 INFO - PROCESS | 3701 | 18:46:50 INFO - PROCESS | 3701 | Cycle 1(1): loaded http://localhost:40484/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40484/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:46:51 INFO - PROCESS | 3701 | RSS: Main: 184750080 18:46:51 INFO - PROCESS | 3701 | 18:46:53 INFO - PROCESS | 3701 | Cycle 1(1): loaded http://localhost:40484/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40484/tests/kraken/driver.html?testName=ai-astar) 18:46:53 INFO - PROCESS | 3701 | RSS: Main: 235929600 18:46:53 INFO - PROCESS | 3701 | 18:46:53 INFO - PROCESS | 3701 | __start_tp_report 18:46:53 INFO - PROCESS | 3701 | _x_x_mozilla_page_load 18:46:53 INFO - PROCESS | 3701 | _x_x_mozilla_page_load_details 18:46:53 INFO - PROCESS | 3701 | |i|pagename|runs| 18:46:53 INFO - PROCESS | 3701 | |0;ai-astar;108;110;119;111;109;111;109;111;110;110 18:46:53 INFO - PROCESS | 3701 | |1;audio-beat-detection;139;115;143;161;115;156;140;120;178;116 18:46:53 INFO - PROCESS | 3701 | |2;audio-dft;160;159;157;162;158;160;160;161;159;162 18:46:53 INFO - PROCESS | 3701 | |3;audio-fft;74;74;74;169;75;74;77;75;172;76 18:46:53 INFO - PROCESS | 3701 | |4;audio-oscillator;85;83;149;83;83;84;139;83;83;142 18:46:53 INFO - PROCESS | 3701 | |5;imaging-gaussian-blur;117;117;117;116;117;117;116;117;117;117 18:46:53 INFO - PROCESS | 3701 | |6;imaging-darkroom;146;146;145;146;146;146;145;146;146;145 18:46:53 INFO - PROCESS | 3701 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;94;95 18:46:53 INFO - PROCESS | 3701 | |8;json-parse-financial;50;58;50;50;50;50;50;51;50;50 18:46:53 INFO - PROCESS | 3701 | |9;json-stringify-tinderbox;59;59;59;60;60;59;59;59;59;58 18:46:53 INFO - PROCESS | 3701 | |10;stanford-crypto-aes;78;72;73;73;76;71;72;71;72;71 18:46:53 INFO - PROCESS | 3701 | |11;stanford-crypto-ccm;129;121;133;127;116;114;115;120;199;135 18:46:53 INFO - PROCESS | 3701 | |12;stanford-crypto-pbkdf2;157;151;161;153;154;155;157;155;152;151 18:46:53 INFO - PROCESS | 3701 | |13;stanford-crypto-sha256-iterative;64;61;62;62;61;63;70;61;61;61 18:46:53 INFO - PROCESS | 3701 | __end_tp_report 18:46:53 INFO - PROCESS | 3701 | __start_cc_report 18:46:53 INFO - PROCESS | 3701 | _x_x_mozilla_cycle_collect,1708 18:46:53 INFO - PROCESS | 3701 | __end_cc_report 18:46:53 INFO - PROCESS | 3701 | __startTimestamp1473990413329__endTimestamp 18:46:53 INFO - PROCESS | 3701 | 18:46:53 INFO - PROCESS | 3701 | ------- Summary: start ------- 18:46:53 INFO - PROCESS | 3701 | Number of tests: 14 18:46:53 INFO - PROCESS | 3701 | 18:46:53 INFO - PROCESS | 3701 | [#0] ai-astar Cycles:10 Average:110.80 Median:110.50 stddev:3.05 (2.8%) stddev-sans-first:3.06 18:46:53 INFO - PROCESS | 3701 | Values: 108.0 110.0 119.0 111.0 109.0 111.0 109.0 111.0 110.0 110.0 18:46:53 INFO - PROCESS | 3701 | 18:46:53 INFO - PROCESS | 3701 | [#1] audio-beat-detection Cycles:10 Average:138.30 Median:141.50 stddev:21.98 (15.5%) stddev-sans-first:23.31 18:46:53 INFO - PROCESS | 3701 | Values: 139.0 115.0 143.0 161.0 115.0 156.0 140.0 120.0 178.0 116.0 18:46:53 INFO - PROCESS | 3701 | 18:46:53 INFO - PROCESS | 3701 | [#2] audio-dft Cycles:10 Average:159.80 Median:160.00 stddev:1.62 (1.0%) stddev-sans-first:1.72 18:46:53 INFO - PROCESS | 3701 | Values: 160.0 159.0 157.0 162.0 158.0 160.0 160.0 161.0 159.0 162.0 18:46:53 INFO - PROCESS | 3701 | 18:46:53 INFO - PROCESS | 3701 | [#3] audio-fft Cycles:10 Average:94.00 Median:74.50 stddev:40.34 (54.1%) stddev-sans-first:42.13 18:46:53 INFO - PROCESS | 3701 | Values: 74.0 74.0 74.0 169.0 75.0 74.0 77.0 75.0 172.0 76.0 18:46:53 INFO - PROCESS | 3701 | 18:46:53 INFO - PROCESS | 3701 | [#4] audio-oscillator Cycles:10 Average:101.40 Median:83.00 stddev:29.04 (35.0%) stddev-sans-first:30.19 18:46:53 INFO - PROCESS | 3701 | Values: 85.0 83.0 149.0 83.0 83.0 84.0 139.0 83.0 83.0 142.0 18:46:53 INFO - PROCESS | 3701 | 18:46:53 INFO - PROCESS | 3701 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:46:53 INFO - PROCESS | 3701 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 18:46:53 INFO - PROCESS | 3701 | 18:46:53 INFO - PROCESS | 3701 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 18:46:53 INFO - PROCESS | 3701 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 145.0 146.0 146.0 145.0 18:46:53 INFO - PROCESS | 3701 | 18:46:53 INFO - PROCESS | 3701 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 18:46:53 INFO - PROCESS | 3701 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 18:46:53 INFO - PROCESS | 3701 | 18:46:53 INFO - PROCESS | 3701 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:50.00 stddev:2.51 (5.0%) stddev-sans-first:2.65 18:46:53 INFO - PROCESS | 3701 | Values: 50.0 58.0 50.0 50.0 50.0 50.0 50.0 51.0 50.0 50.0 18:46:53 INFO - PROCESS | 3701 | 18:46:53 INFO - PROCESS | 3701 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:0.57 (1.0%) stddev-sans-first:0.60 18:46:53 INFO - PROCESS | 3701 | Values: 59.0 59.0 59.0 60.0 60.0 59.0 59.0 59.0 59.0 58.0 18:46:53 INFO - PROCESS | 3701 | 18:46:53 INFO - PROCESS | 3701 | [#10] stanford-crypto-aes Cycles:10 Average:72.90 Median:72.50 stddev:2.33 (3.2%) stddev-sans-first:1.58 18:46:53 INFO - PROCESS | 3701 | Values: 78.0 72.0 73.0 73.0 76.0 71.0 72.0 71.0 72.0 71.0 18:46:53 INFO - PROCESS | 3701 | 18:46:53 INFO - PROCESS | 3701 | [#11] stanford-crypto-ccm Cycles:10 Average:130.90 Median:128.00 stddev:25.07 (19.6%) stddev-sans-first:26.58 18:46:53 INFO - PROCESS | 3701 | Values: 129.0 121.0 133.0 127.0 116.0 114.0 115.0 120.0 199.0 135.0 18:46:53 INFO - PROCESS | 3701 | 18:46:53 INFO - PROCESS | 3701 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.60 Median:155.00 stddev:3.13 (2.0%) stddev-sans-first:3.20 18:46:53 INFO - PROCESS | 3701 | Values: 157.0 151.0 161.0 153.0 154.0 155.0 157.0 155.0 152.0 151.0 18:46:53 INFO - PROCESS | 3701 | 18:46:53 INFO - PROCESS | 3701 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:62.00 stddev:2.80 (4.5%) stddev-sans-first:2.92 18:46:53 INFO - PROCESS | 3701 | Values: 64.0 61.0 62.0 62.0 61.0 63.0 70.0 61.0 61.0 61.0 18:46:53 INFO - PROCESS | 3701 | -------- Summary: end -------- 18:46:53 INFO - PROCESS | 3701 | 18:46:55 INFO - TEST-INFO | 3701: exit 0 18:46:55 INFO - TEST-OK | kraken | took 61246ms 18:46:55 INFO - SUITE-END | took 1177s 18:46:55 INFO - Completed test suite (00:19:37) 18:46:55 INFO - javascript benchmark 18:46:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3260.413125637385, 3263.8204898730573, 3287.4411350070486, 3304.9834750826244, 3334.4998250262456, 5646.520045796107, 5745.482906538421, 5752.309722833261, 5757.856053598652, 5780.3930587288305, 5681.318241810988, 5764.048931926238, 5774.249347584808, 5774.769009239636, 5777.566682498808, 5041.697498150104, 5117.565006974393, 5188.11801993662, 5243.134882744343, 5265.473452654742, 5122.590192784568, 5175.197844334134, 5179.404368497614, 5180.870478238036, 5203.089459344618, 5112.693238405689, 5162.741862906859, 5171.560417364529, 5180.611454140954, 5181.481851814807, 5214.921776173361, 5222.5038621331105, 5232.424433312332, 5236.502532259429, 5269.394019687747, 4987.6758010729545, 5037.0681423936685, 5054.570361519275, 5131.3329267195395, 5136.332276804029, 5589.692566908859, 5610.831675049755, 5614.382417934024, 5627.127795191711, 5650.378458369576, 6019.819405417845, 6026.879462410771, 6027.517798576104, 6034.215551978258, 6040.667763273018, 5864.38423965486, 6057.182280392135, 6059.333473317932, 6065.3934606539, 6080.087986801971, 5723.771049158028, 5731.369549349568, 5751.223584816038, 5758.078707406795, 5763.221965034667, 6212.223472065992, 6248.0, 6270.623762574261, 6272.1219029335925, 6275.6548389839, 5792.044312688444, 5844.386339434383, 5875.794347197828, 5898.262717160355, 6037.0, 5952.8809423812145, 5955.702214889238, 5995.430434108753, 6041.395860414012, 6043.2748070231855, 5928.496077833346, 5958.19564358806, 5969.04495280753, 5972.134040564094, 5985.46130848217, 5916.2013128228, 5935.495482884004, 5960.731767070492, 5968.343482216953, 6070.332263451017, 4654.953450465519, 4660.6970546914345, 4660.813567457331, 4662.487126416091, 4664.696794708333, 4070.531888832763, 4110.568390319033, 4118.19695159447, 4162.042730172077, 4169.749815011049, 5713.600047996601, 5715.371309155989, 5715.657060576295, 5726.942730572724, 5753.482186603226, 6053.878922421527, 6104.633721976696, 6115.1744514490865, 6124.234470691164, 6125.632462052291, 5558.027345214571, 5572.164175373727, 5611.242482264842, 5630.774769009194, 5698.373178950312, 5223.164293713064, 5230.241614965808, 5232.450592687789, 5232.92150617741, 5262.894742105136, 6196.566240363221, 6235.127082208494, 6244.12582238487, 6254.155688981929, 6262.906056409158, 5904.734286957011, 5921.940780592139, 5923.940760592339, 5955.076963070731, 6065.60573562717, 6160.075988601746, 6163.630182189083, 6165.753369865155, 6184.319724830355, 6186.288576813544, 6018.939810601838, 6027.36712645166, 6031.09533569968, 6033.818985430268, 6036.698165091816, 5895.14520394549, 5980.102984552352, 6014.759409623566, 6128.1726966858905, 6142.293636231891, 5897.616654917417, 5934.376890426443, 5941.435563621449, 5978.880422391615, 5991.7902873399225, 5872.265966754156, 5902.911456328073, 5914.260717410324, 5916.585838991401, 5923.111533270044, 5667.376588575171, 5777.94222057774, 5810.5061069809535, 5866.50134738552, 5868.706564671664, 5957.940420595912, 5970.462658360769, 5975.581709280309, 6007.789727359522, 6014.699265036643, 5802.854928626818, 5806.0, 5806.477417032487, 5807.883842323215, 5818.44724751148], "value": 5577.246035577937, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.0908623435496, 625.8497960489483, 632.3960617610182, 647.656741926779, 662.1557514169433, 36011.45982810257, 39788.20423591526, 47619.28571071432, 48582.0, 48876.0, 49471.257931130975, 49976.75011624946, 50036.0, 50066.49667516628, 50072.24891626628, 9197.356185067032, 9327.440353578773, 9338.252939764803, 9342.065793420637, 9342.719718408458, 8452.408331416804, 10884.346939183635, 11134.10927125832, 11152.219344645879, 11543.422828858565, 11337.94331028348, 11341.0, 11414.71463213418, 11435.599754008612, 11443.427828608577, 11137.276077055007, 11166.888331116746, 11211.551537938472, 11213.551457941672, 11228.494717737722, 11047.226694131414, 11071.16966227528, 11092.55629774808, 11099.72250693731, 11112.61105861299, 47448.28827567589, 47645.0, 47661.285080723814, 47876.52123478756, 47981.280280795814, 3336.065901547571, 3374.1227280906896, 3452.171478845084, 3586.7847929124073, 4019.3970904364287, 35964.82017589895, 36325.818370908244, 36346.0, 36371.81814090939, 36586.817065914765, 4735.621150307967, 4806.6154707623655, 4821.710697358135, 4831.744641897689, 4891.192953162759, 377960.0, 378558.0, 378909.0, 380922.0, 381555.0, 67216.6639166801, 67579.3242067573, 67703.66148169326, 67726.32273677299, 67790.66104669445, 41883.0, 42835.78582107132, 43068.0, 70056.64971675212, 70169.24576885618, 16007.839921600635, 16056.839431605767, 16140.919295403448, 16141.0, 16154.0, 10857.0, 12027.579034733742, 12198.57304994321, 12282.508699651913, 12303.077269204845, 20484.897575512325, 21379.358619241448, 21400.892995534923, 21462.0, 21578.568428631654, 7491.363234125051, 7726.806829829299, 7729.5748733819055, 7732.30409263169, 7881.290683838482, 1054.7415883108688, 1059.3114475590774, 1074.908632766208, 1096.950637221327, 1144.7939370913316, 35738.0, 35764.0, 35895.0, 36016.27967440689, 36039.81980090083, 1160.0, 1185.8338236907477, 1186.0867132308247, 1192.1059205595802, 1240.621610408816, 18557.536061598566, 18558.164882580317, 18695.0, 18757.34349297768, 18824.15291311894, 644087.0, 650646.0, 650762.0, 650915.0, 651191.0, 65563.67218163879, 66195.33804661989, 66201.33798662048, 66303.0, 66366.66816665886, 1332.7534406134896, 1384.7715127004133, 1388.4931999819994, 1434.512265829623, 1457.7813328000884, 34749.47875781815, 36302.45546316754, 36474.452883207305, 36661.63790950329, 36942.630573694994, 50458.49541504538, 50500.74749626228, 50548.24177637412, 50654.74672626613, 50762.49237507577, 444025.0, 444650.0, 446475.0, 447068.0, 447222.0, 30803.38393232078, 30994.845025774728, 31389.52915706312, 31598.52602210923, 33228.66771332256, 52800.47199527956, 52882.735586321825, 53084.469155307954, 53469.19796202982, 53792.0, 43433.565664342954, 47442.28836567523, 47650.52349476599, 48097.519024809306, 49270.0, 45506.31740523961, 46637.766811165726, 46659.53340466688, 48589.75705121452, 48954.75522622507, 46112.308315375965, 46526.76736616295, 46883.53116468792, 47562.76218619023, 47600.2859957094, 38337.0, 40599.797001015984, 40686.38970415505, 40768.592314077665, 41207.05311792176, 30531.694683053774, 30894.211397233466, 31048.534271985485, 31163.376732465676, 31259.374812503167], "value": 22066.52629184691, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.752572065297, 393.8463999040373, 412.99174016519663, 418.5793277755855, 420.07164167190507, 14494.5651630451, 20170.193192272327, 20244.493887652818, 20362.083706233236, 21336.57326853466, 20093.799062009413, 20557.794422055777, 20579.27972520962, 20653.483662908435, 20938.26716064938, 5767.394423585537, 5801.883962320751, 5810.215620891189, 5820.476157145856, 5878.441548052928, 5706.543476521868, 5807.680577568232, 5849.9707501462435, 5856.619319744224, 5858.88282234355, 5766.682832444214, 5824.242848429698, 5854.619449735775, 5858.7949421770245, 5866.970665146669, 5605.383407825136, 5793.942060579404, 5817.0983497557945, 5921.348651648337, 5925.407459254083, 5660.066089095294, 5763.279590051244, 5791.507721843648, 5796.82609521715, 5812.941870581325, 13864.514741983981, 14387.78418323726, 14461.132332060004, 14464.855351446457, 14488.565343039725, 2346.8005219556358, 2451.3994071452607, 2508.811839112074, 2576.8840402181945, 3023.561583570392, 10584.841227381596, 10816.188785841094, 11902.107341949388, 15360.078395296317, 15502.147381893992, 1933.7389452423884, 2007.989960050205, 2010.8491863110326, 2022.312413779322, 2026.523766914774, 65589.67205163944, 73934.63032684856, 74512.25487745108, 84865.57567212124, 84916.0, 9892.120497105527, 10450.581976720998, 10573.788524229472, 11368.886311136948, 11865.762684746256, 14370.49703260381, 14438.566842994727, 14454.783178252335, 14568.0, 14570.41718331255, 7599.316061554487, 7687.154413014564, 7903.644336004893, 7986.600669966595, 8045.396595255379, 7432.405407567381, 7475.551466912003, 7504.774856754306, 7620.657070431733, 7640.083190017233, 4257.40396344512, 4314.590113939171, 4409.735415875058, 4476.820927162877, 4499.325101234841, 2048.897555122238, 2092.9895350523457, 2120.957580848374, 2143.164165975271, 2219.922302719397, 911.6854685133618, 993.9403635781877, 1035.8756949165997, 1073.9892601073889, 1150.4880328254014, 19677.50806229827, 21020.684689729667, 21060.262890799055, 21163.04766285521, 21274.574508509744, 994.796066806303, 1021.8824835144054, 1028.4292217819182, 1056.4031322302874, 1130.8118790141102, 9991.0, 11834.763304733857, 11854.466549005316, 12023.519059237533, 12051.27692338445, 486496.0, 486762.0, 487046.0, 487083.0, 508817.0, 56584.78546464005, 80200.19799802043, 96206.51896740471, 99436.00563994267, 99450.0, 961.1157734883924, 982.5087456271864, 1005.9899401006043, 1007.1870632860821, 1015.441507171053, 18166.455006349832, 18823.34118305853, 19107.0, 19139.904300478407, 19410.708839367704, 43049.0, 43052.0, 43646.56353436425, 44390.556094438645, 44987.0, 56099.0, 57014.0, 59980.400195997485, 65842.67078664576, 66925.66537167283, 8845.778855528662, 8906.777330566787, 8952.955235223782, 8954.104589540993, 9214.631414743335, 12332.383380831103, 13878.722425551634, 14092.506762263682, 14290.35693393779, 14340.713185736018, 12176.512939482676, 12188.329641869783, 12489.125761196628, 12595.0, 12641.2415255085, 12066.155369124077, 12179.573714919936, 12204.328761917825, 12379.390679211645, 12534.561290354794, 12316.0, 12759.234445933274, 12780.424880880568, 12846.0, 12849.871501284868, 8912.598933048159, 8912.73261802147, 9180.908190918006, 9227.35408521397, 9464.526773661151, 8891.888513935757, 12407.44166512491, 12687.22378866941, 12881.291528965998, 13050.804237936629], "value": 9376.903292760508, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1537.6770878115594, 1548.8141423029238, 1704.4630941253504, 1799.6850551153545, 1868.9065546722668, 9403.200727938125, 9875.407475551472, 9878.703638890845, 9887.555060022298, 9890.0, 8396.286315663176, 8898.332625053115, 9244.075592440753, 9268.443893366402, 9650.806983860319, 2435.610302351624, 2495.950080998379, 2496.2012156110054, 2499.687539057618, 2503.849769013865, 2420.2376251480814, 2455.5702752018415, 2456.1771806444863, 2457.447074408262, 2459.053264493165, 2475.084218839023, 2484.888180031894, 2519.60946053362, 2520.3573088862313, 2522.5207210629924, 2371.2886134159685, 2380.523895220963, 2384.201292566992, 2388.056717596557, 2409.204962362416, 2401.5677178107935, 2408.915687950931, 2423.4426082001237, 2436.4030812450887, 2437.524682686877, 9525.28560357976, 9591.472469014201, 9598.424094554279, 9600.039996000414, 9607.13535781783, 5453.727313634303, 5622.999999999959, 5628.605997580171, 5629.211910332557, 5629.9437005629825, 3433.1588760753525, 3478.617352091268, 3555.0, 3557.804320762357, 3565.3938830398633, 1306.4643496166525, 1312.08154291996, 1320.1089264746258, 1345.1727187779445, 1352.648311439033, 13608.251546164956, 13856.93071534636, 13864.653383665489, 13879.306034698224, 13892.097013694078, 4973.2042873140845, 5075.314832497639, 5076.137056700296, 5124.692518448905, 5283.101872681576, 11470.598529051444, 11543.711407214887, 11546.13403994687, 11547.307161570332, 11552.76894462106, 5500.4224556420995, 5523.447655234444, 5533.723313834373, 5536.723163841792, 5538.16927460876, 4625.884352891205, 5064.518870707277, 5196.974015129976, 5291.603129765283, 5316.973415132899, 4381.1237752449, 4412.448443944507, 4457.130859482435, 4500.819967201341, 4514.887127821765, 1371.1087792934673, 1411.1674112273809, 1412.4773833681397, 1427.06527224668, 1430.3277459593974, 834.4492634861084, 954.1698722111807, 1042.588177669816, 1062.85651437055, 1067.756064185231, 6402.103705481236, 6618.966905165444, 6638.6680665966505, 6659.633720145439, 6780.72877084911, 1011.9392836429838, 1078.1105587889865, 1092.5848177692585, 1095.200503632337, 1126.8760436351995, 4391.714538554984, 4558.2251017327135, 4562.475315338712, 4695.483496815347, 4846.684965477232, 11536.826947595793, 12061.216020958609, 12099.33453660057, 12436.626901192978, 12565.685857853627, 12286.75426491465, 12317.07621928359, 12335.013198944063, 12336.50653973831, 12437.62687119388, 8792.296616270554, 8882.422642528216, 9084.591193396313, 9088.727338179866, 9100.908990910139, 6302.810915672537, 6337.144485494399, 6339.080833279057, 6670.199576050711, 6953.20038195614, 10970.286931349438, 11141.610043648434, 11170.329780213213, 11218.551257949917, 11332.263402878787, 11282.605108821152, 11292.096632269235, 11455.198136130724, 11525.308481491138, 11640.650780476599, 8661.480311181098, 8706.695265665672, 8738.645509945969, 8792.164744349277, 8810.911890881265, 9305.627774888928, 9339.112784285482, 9345.953270233606, 9401.435913845191, 9412.67055653049, 8332.750017499484, 8364.958175209329, 8485.57572121379, 8496.0, 8592.183742544448, 8030.0, 8090.514569125636, 8136.186381362053, 8161.510309381456, 8299.585020749058, 7374.299441553044, 7385.29839665231, 7440.2931721486375, 7442.851142977219, 7459.850802984019, 5676.120201368794, 5793.594448388572, 5875.794347197828, 5901.7639294427745, 5932.16949627044, 5719.599628025998, 5795.304563452412, 5842.912356314574, 5864.0, 5879.794207202727], "value": 5075.892940663805, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.2920221523724, 335.36783163736357, 340.2395645731789, 350.7965380079554, 354.62232722150907, 5386.06101803351, 5438.564914806806, 5453.972730136349, 5460.754266058027, 5471.127657979563, 5371.818199995994, 5376.5752075699875, 5380.354357477108, 5402.243685883969, 5410.08028635132, 4087.2234275487635, 4146.336586146217, 4277.871663850089, 4314.525402205755, 4323.1353729254115, 4242.363645453192, 4256.382824490447, 4273.230818452677, 4282.593153650414, 4308.138372325532, 4231.70378073535, 4235.110626768367, 4239.071222593712, 4264.402983582301, 4279.935800962988, 4058.959410405888, 4062.573429789859, 4240.957590424118, 4272.33778764292, 4273.465816772903, 3756.549214094326, 3775.3959366501504, 3775.9433608495897, 3791.0711875590655, 3799.715021373408, 5298.496642818926, 5305.575553955713, 5329.415233611734, 5346.652467589594, 5363.9080758504215, 4795.736234507136, 4837.903241935177, 4840.1771698810935, 4857.854264372075, 4888.902221955576, 5517.834464966058, 5536.806211782608, 5540.058190107691, 5540.63985840919, 5598.86002849932, 4105.527864295615, 4130.607592278729, 4155.252054630166, 4167.604077612622, 4172.83308667656, 5702.344230413514, 5822.475977162076, 5827.912581311284, 5839.737211825478, 5877.9118313225335, 4271.530131685512, 4292.763897985578, 4293.957060429419, 4294.592013758688, 4302.634276086505, 4324.394584758136, 4334.501532323823, 4351.891202719894, 4353.36876152956, 4357.891052723708, 4731.597814185764, 4740.810367585258, 4764.547368000034, 4818.373611430562, 4829.420469543677, 5598.384177740445, 5599.8880022400135, 5606.635568688022, 5613.578981576316, 5618.297712785902, 1479.1125324805246, 1496.6258435391153, 1501.1893577468045, 1503.9849601504063, 1513.402206128573, 962.6186422483759, 966.21253678252, 987.4865070163474, 987.8221920054316, 999.4053538144792, 5320.600954928396, 5398.001369746609, 5433.673979561239, 5455.263539422127, 5460.426655201226, 5776.768929242868, 5781.71091445426, 5783.595148339576, 5817.592768506164, 5832.62087964281, 5196.168613021899, 5387.542058924957, 5444.891102177934, 5470.152126420411, 5495.708508500499, 3026.0481989116897, 3044.2389402649337, 3050.8474576271096, 3051.9542206866913, 3064.877404903779, 5831.653026529072, 5934.406559344031, 5953.9878220702585, 5996.760129594854, 6040.942835003944, 5779.393163717833, 5831.475167234969, 5837.474627283565, 5876.529877609781, 5912.408759124053, 5934.584579079423, 5945.554083443759, 5949.286085669747, 5959.195508606369, 6014.308354539284, 5518.2826232589505, 5737.713114344349, 5787.565932554991, 5790.160426738184, 5853.882922341614, 5679.233303504139, 5685.431456854281, 5720.427957204246, 5721.396764472239, 5726.828195154152, 5820.563457740602, 5922.703864806656, 5958.314793798595, 6013.9097913532205, 6016.127661488972, 4130.690198235085, 4150.335946248586, 4158.646515046134, 4160.6463450605825, 4179.791010449526, 5715.799947002001, 5743.483086522233, 5779.162021506774, 5782.190493330853, 5863.73613187399, 5607.691576963306, 5721.742521586455, 5758.740856661544, 5771.1920331154515, 5798.478136967693, 5601.887962240814, 5617.522510586646, 5634.577406694596, 5660.688662123623, 5665.546756259407, 5541.556675465872, 5586.413426590149, 5622.634528755618, 5635.887282254414, 5638.88722225545, 5191.169412893919, 5433.619646624698, 5641.351244606923, 5663.235463212413, 5687.0, 5525.889482210414, 5534.284371844741, 5544.807866308763, 5555.361168455234, 5579.302587176603], "value": 4335.767323034195, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.626614890225, 835.3943391041496, 839.3788596438634, 932.7574830544058, 951.2722767083178, 15416.229188540585, 16257.18714064298, 16280.185990700476, 16353.100579468124, 16447.83552164478, 15941.20293985302, 16067.116308603023, 16226.0, 16256.268467918942, 16292.674146517062, 861.1947828780095, 890.6259371064136, 891.7146513115806, 896.3277541843618, 897.5198268926126, 888.5379602606673, 891.2023738753811, 891.4383938118976, 892.098980030171, 892.5180402582598, 933.8085692433035, 937.7327461673425, 938.1556599060832, 938.441627231796, 938.9014153513886, 928.0, 928.5682157796657, 928.5960607135918, 929.0430856218072, 929.0709290709291, 684.3567046975827, 687.0793137196131, 687.6355531568277, 687.656171914043, 688.1501345837886, 15493.612659683486, 15507.612309692347, 15546.222688865511, 15549.067055976677, 15553.066815991077, 14430.711385772225, 14454.710905781825, 14508.782368264485, 14616.780748288784, 14755.926220368936, 12137.0, 12285.754284914341, 12341.259524428473, 12342.0, 12803.359832008362, 4079.653229475498, 4094.28350038742, 4105.630493255591, 4230.598093181143, 4237.555056719062, 55236.72381638106, 58261.417385826026, 72547.63726181335, 83878.16121838764, 86422.56788716016, 12756.808647870104, 12932.288724120075, 12941.158824676366, 13330.333483325794, 13429.328533573282, 22978.65532017021, 22996.7700322998, 23003.53992920132, 23044.65433018506, 23079.88460057689, 9065.456072635663, 9117.772055698528, 9131.406458580172, 9253.120953509406, 9254.0, 4906.11689895815, 4958.157113290749, 4992.5756310714, 5140.40885298188, 5170.689758614495, 3927.862524811675, 4090.0, 4095.28332541804, 4095.836166553364, 4096.631303182728, 702.3187508117118, 703.3599424523659, 704.4998051383575, 715.8675645005691, 723.2405973727565, 670.6043434373743, 670.8054664147343, 684.090160087083, 726.5204964723257, 740.9481336306407, 10435.791284174426, 10441.425721585385, 10463.215258855616, 10567.520502478232, 10852.186086043579, 809.9271065604124, 816.0, 820.2617644120339, 821.2526600793369, 832.92087251711, 5008.424031236382, 5325.520703136736, 5358.758855851496, 5373.274607927958, 5378.408375078739, 128231.0, 131378.34310828385, 132653.0, 137285.0, 142438.0, 76228.0, 92992.0, 115396.42301788437, 115513.0, 115577.4221128889, 94654.52672736593, 95119.0, 96241.0, 96897.0, 97240.51379742914, 10316.742081448025, 10484.370937743759, 10503.47482625881, 10695.839562406725, 10776.084032857296, 52078.73960630173, 53369.19946200888, 54054.729726351114, 54254.643633909465, 56004.0, 80490.59754701189, 81701.0, 82415.58792206, 82690.58654706688, 84837.0, 13912.165270083828, 14120.576382708536, 14251.144931304156, 14459.638509037359, 14750.852491474949, 14618.853811462175, 14683.55949321522, 14867.18230497333, 15034.398624054915, 15054.397824086915, 14070.366833492722, 14310.141391516543, 14425.567232983027, 14513.0, 14677.926610367307, 13986.300684965914, 14351.928240358731, 14431.85568144305, 14440.494582689555, 14729.26353682333, 12865.763406580732, 13012.0, 13130.868691312964, 13668.931655342058, 13844.446222151, 11351.318920864775, 11523.596674116701, 11583.6524904253, 11802.468888899848, 12011.918927296585, 11641.883581164419, 11680.299182049104, 11781.0, 11983.28100313984, 12030.699232518908], "value": 8130.990895967268, "unit": "score"}], "name": "dromaeo_css", "value": 7688.291876046311, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 110.0, 119.0, 111.0, 109.0, 111.0, 109.0, 111.0, 110.0, 110.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 115.0, 143.0, 161.0, 115.0, 156.0, 140.0, 120.0, 178.0, 116.0], "value": 138.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 157.0, 162.0, 158.0, 160.0, 160.0, 161.0, 159.0, 162.0], "value": 159.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 169.0, 75.0, 74.0, 77.0, 75.0, 172.0, 76.0], "value": 94.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 149.0, 83.0, 83.0, 84.0, 139.0, 83.0, 83.0, 142.0], "value": 101.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 58.0, 50.0, 50.0, 50.0, 50.0, 50.0, 51.0, 50.0, 50.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 60.0, 60.0, 59.0, 59.0, 59.0, 59.0, 58.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 73.0, 73.0, 76.0, 71.0, 72.0, 71.0, 72.0, 71.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 121.0, 133.0, 127.0, 116.0, 114.0, 115.0, 120.0, 199.0, 135.0], "value": 130.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 151.0, 161.0, 153.0, 154.0, 155.0, 157.0, 155.0, 152.0, 151.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 62.0, 62.0, 61.0, 63.0, 70.0, 61.0, 61.0, 61.0], "value": 62.6, "unit": "score"}], "name": "kraken", "value": 1492.6999999999998, "alertThreshold": 2.0}]} 18:46:55 INFO - Return code: 0 18:46:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:46:55 INFO - # TBPL SUCCESS # 18:46:55 INFO - Running post-action listener: _resource_record_post_action 18:46:55 INFO - [mozharness: 2016-09-16 01:46:55.637217Z] Finished run-tests step (success) 18:46:55 INFO - Running post-run listener: _resource_record_post_run 18:46:55 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 7766016; Write bytes: 493740032; Read time: 704; Write time: 434960 18:46:55 INFO - TinderboxPrint: CPU usage
12.9% 18:46:55 INFO - TinderboxPrint: I/O read bytes / time
7,766,016 / 704 18:46:55 INFO - TinderboxPrint: I/O write bytes / time
493,740,032 / 434,960 18:46:55 INFO - TinderboxPrint: CPU idle
8,297.1 (87.1%) 18:46:55 INFO - TinderboxPrint: CPU user
1,183.8 (12.4%) 18:46:55 INFO - TinderboxPrint: Swap in / out
0 / 0 18:46:55 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79335424; Read time: 0; Write time: 57036 18:46:55 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 5525504; Write bytes: 413978624; Read time: 544; Write time: 377816 18:46:56 INFO - Running post-run listener: _upload_blobber_files 18:46:56 INFO - Blob upload gear active. 18:46:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:46:56 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 18:46:56 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'] 18:46:56 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 18:46:56 INFO - (blobuploader) - INFO - Open directory for files ... 18:46:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:46:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:47:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:47:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:47:02 INFO - (blobuploader) - INFO - Done attempting. 18:47:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:47:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:47:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:47:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:47:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:47:05 INFO - (blobuploader) - INFO - Done attempting. 18:47:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:47:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:47:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:47:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:47:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:47:05 INFO - (blobuploader) - INFO - Done attempting. 18:47:05 INFO - (blobuploader) - INFO - Iteration through files over. 18:47:05 INFO - Return code: 0 18:47:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:47:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:47:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dac13a2fa717cb886315227b4baed6866b2c9e5e0fe90160d9c23bb5ab2c0aec4f80787455f8329c50d42ed3e2fd304a0075ab28843d1d2c6fdf7bf92da378c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8a040aea5a98b263c3dcbdea3d1a6b9869906dc63267de47aa6126a2fc6c7088cb7f4b3d7c7ceda11f2152f9eeec0d1a8aaa75eee04b32ea041338d326f45fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ed6701ab5e038a0a390d00dd90a8ee0304d7712c03184c3ef6ce89abe29d6e22e96d0d6a1a260d7773caf8543ba58af4bb08ea64bf129f59b46baf877e83a47"} 18:47:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:47:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:47:05 INFO - Contents: 18:47:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dac13a2fa717cb886315227b4baed6866b2c9e5e0fe90160d9c23bb5ab2c0aec4f80787455f8329c50d42ed3e2fd304a0075ab28843d1d2c6fdf7bf92da378c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8a040aea5a98b263c3dcbdea3d1a6b9869906dc63267de47aa6126a2fc6c7088cb7f4b3d7c7ceda11f2152f9eeec0d1a8aaa75eee04b32ea041338d326f45fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ed6701ab5e038a0a390d00dd90a8ee0304d7712c03184c3ef6ce89abe29d6e22e96d0d6a1a260d7773caf8543ba58af4bb08ea64bf129f59b46baf877e83a47"} 18:47:05 INFO - Running post-run listener: copy_logs_to_upload_dir 18:47:05 INFO - Copying logs to upload dir... 18:47:05 INFO - mkdir: /builds/slave/test/build/upload/logs 18:47:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1686.730981 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 6 secs) (at 2016-09-15 18:47:05.878071) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-15 18:47:05.882114) ========= 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=1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960 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/dac13a2fa717cb886315227b4baed6866b2c9e5e0fe90160d9c23bb5ab2c0aec4f80787455f8329c50d42ed3e2fd304a0075ab28843d1d2c6fdf7bf92da378c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8a040aea5a98b263c3dcbdea3d1a6b9869906dc63267de47aa6126a2fc6c7088cb7f4b3d7c7ceda11f2152f9eeec0d1a8aaa75eee04b32ea041338d326f45fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ed6701ab5e038a0a390d00dd90a8ee0304d7712c03184c3ef6ce89abe29d6e22e96d0d6a1a260d7773caf8543ba58af4bb08ea64bf129f59b46baf877e83a47"} build_url:https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011212 build_url: 'https://queue.taskcluster.net/v1/task/Tqd6sa-3QBu_qKxO8ZcmxA/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/dac13a2fa717cb886315227b4baed6866b2c9e5e0fe90160d9c23bb5ab2c0aec4f80787455f8329c50d42ed3e2fd304a0075ab28843d1d2c6fdf7bf92da378c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8a040aea5a98b263c3dcbdea3d1a6b9869906dc63267de47aa6126a2fc6c7088cb7f4b3d7c7ceda11f2152f9eeec0d1a8aaa75eee04b32ea041338d326f45fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ed6701ab5e038a0a390d00dd90a8ee0304d7712c03184c3ef6ce89abe29d6e22e96d0d6a1a260d7773caf8543ba58af4bb08ea64bf129f59b46baf877e83a47"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-15 18:47:05.909182) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-15 18:47:05.909502) ========= 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=1f5564757af27664ca02d71f000001b0-1473987868.216862-1348822960 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004441 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-15 18:47:05.994041) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-15 18:47:05.994537) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-15 18:47:05.995021) ========= ========= Total master_lag: 0.19 =========