builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0025 starttime: 1465235286.34 results: success (0) buildid: 20160606053329 builduid: d7acf4330f0b47b6844d82d15649fe74 revision: 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:06.336473) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:06.336976) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:06.337267) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:07.156510) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:07.156813) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-06 10:48:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.39M=0.001s 2016-06-06 10:48:07 (8.39 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.151888 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:07.334450) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:07.334863) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020507 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:07.383903) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-06 10:48:07.384268) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 --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 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-06 10:48:07,458 truncating revision to first 12 chars 2016-06-06 10:48:07,458 Setting DEBUG logging. 2016-06-06 10:48:07,458 attempt 1/10 2016-06-06 10:48:07,458 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5c8f37e61ac7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-06 10:48:08,573 unpacking tar archive at: mozilla-aurora-5c8f37e61ac7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.371145 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-06 10:48:08.777414) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:08.777855) ========= script_repo_revision: 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:08.778272) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:08.778598) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:08.800664) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-06-06 10:48:08.801059) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_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/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:48:08 INFO - MultiFileLogger online at 20160606 10:48:08 in /builds/slave/test 10:48:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 10:48:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:48:08 INFO - {'append_to_log': False, 10:48:08 INFO - 'base_work_dir': '/builds/slave/test', 10:48:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:48:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:48:08 INFO - 'branch': 'Mozilla-Aurora', 10:48:08 INFO - 'buildbot_json_path': 'buildprops.json', 10:48:08 INFO - 'config_files': ('talos/mac_config.py',), 10:48:08 INFO - 'default_actions': ('clobber', 10:48:08 INFO - 'read-buildbot-config', 10:48:08 INFO - 'download-and-extract', 10:48:08 INFO - 'populate-webroot', 10:48:08 INFO - 'create-virtualenv', 10:48:08 INFO - 'install', 10:48:08 INFO - 'run-tests'), 10:48:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:48:08 INFO - 'download_minidump_stackwalk': True, 10:48:08 INFO - 'download_symbols': 'ondemand', 10:48:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:48:08 INFO - 'tooltool.py': '/tools/tooltool.py', 10:48:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:48:08 INFO - '/tools/misc-python/virtualenv.py')}, 10:48:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:48:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:48:08 INFO - 'installer_path': 'installer.exe', 10:48:08 INFO - 'log_level': 'info', 10:48:08 INFO - 'log_name': 'talos', 10:48:08 INFO - 'log_to_console': True, 10:48:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:48:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:48:08 INFO - 'opt_config_files': (), 10:48:08 INFO - 'pip_index': False, 10:48:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:48:08 INFO - 'cmd': ('bash', 10:48:08 INFO - '-c', 10:48:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:48:08 INFO - 'enabled': True, 10:48:08 INFO - 'halt_on_failure': False, 10:48:08 INFO - 'name': 'check_screen_resolution'},), 10:48:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:48:08 INFO - 'cmd': ('bash', 10:48:08 INFO - '-c', 10:48:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:48:08 INFO - 'enabled': True, 10:48:08 INFO - 'halt_on_failure': False, 10:48:08 INFO - 'name': 'check_screen_resolution'},), 10:48:08 INFO - 'run_cmd_checks_enabled': True, 10:48:08 INFO - 'sps_profile': False, 10:48:08 INFO - 'sps_profile_interval': 0, 10:48:08 INFO - 'suite': 'dromaeojs', 10:48:08 INFO - 'system_bits': '32', 10:48:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:48:08 INFO - 'title': 't-yosemite-r7-0025', 10:48:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:48:08 INFO - 'use_talos_json': True, 10:48:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:48:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:48:08 INFO - 'work_dir': 'build'} 10:48:08 INFO - ##### 10:48:08 INFO - ##### Running clobber step. 10:48:08 INFO - ##### 10:48:08 INFO - Running pre-action listener: _resource_record_pre_action 10:48:08 INFO - Running main action method: clobber 10:48:08 INFO - rmtree: /builds/slave/test/build 10:48:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:48:09 INFO - Running post-action listener: _resource_record_post_action 10:48:09 INFO - ##### 10:48:09 INFO - ##### Running read-buildbot-config step. 10:48:09 INFO - ##### 10:48:09 INFO - Running pre-action listener: _resource_record_pre_action 10:48:09 INFO - Running main action method: read_buildbot_config 10:48:09 INFO - Using buildbot properties: 10:48:09 INFO - { 10:48:09 INFO - "project": "", 10:48:09 INFO - "product": "firefox", 10:48:09 INFO - "script_repo_revision": "production", 10:48:09 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:48:09 INFO - "repository": "", 10:48:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:48:09 INFO - "buildid": "20160606053329", 10:48:09 INFO - "pgo_build": "False", 10:48:09 INFO - "basedir": "/builds/slave/test", 10:48:09 INFO - "buildnumber": 31, 10:48:09 INFO - "slavename": "t-yosemite-r7-0025", 10:48:09 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 10:48:09 INFO - "platform": "yosemite_r7", 10:48:09 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:48:09 INFO - "branch": "mozilla-aurora", 10:48:09 INFO - "repo_path": "releases/mozilla-aurora", 10:48:09 INFO - "revision": "5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64", 10:48:09 INFO - "stage_platform": "macosx64", 10:48:09 INFO - "builduid": "d7acf4330f0b47b6844d82d15649fe74", 10:48:09 INFO - "slavebuilddir": "test" 10:48:09 INFO - } 10:48:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 10:48:09 INFO - Running post-action listener: _resource_record_post_action 10:48:09 INFO - ##### 10:48:09 INFO - ##### Running download-and-extract step. 10:48:09 INFO - ##### 10:48:09 INFO - Running pre-action listener: _resource_record_pre_action 10:48:09 INFO - Running main action method: download_and_extract 10:48:09 INFO - mkdir: /builds/slave/test/build/tests 10:48:09 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')]} 10:48:09 INFO - https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:48:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 10:48:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 10:48:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 10:48:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 10:48:11 INFO - Downloaded 1199 bytes. 10:48:11 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 10:48:11 INFO - Using the following test package requirements: 10:48:11 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 10:48:11 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:11 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 10:48:11 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:11 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 10:48:11 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:11 INFO - u'jsshell-mac.zip'], 10:48:11 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:11 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 10:48:11 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 10:48:11 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:11 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 10:48:11 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:11 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 10:48:11 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:11 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 10:48:11 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:11 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 10:48:11 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:11 INFO - https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:48:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:48:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:48:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:48:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:48:14 INFO - Downloaded 18466753 bytes. 10:48:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:48:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:48:17 INFO - Return code: 0 10:48:17 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 10:48: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')]} 10:48:17 INFO - https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:48:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:48:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:48:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:48:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:48:19 INFO - Downloaded 18466753 bytes. 10:48:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:48:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:48:22 INFO - Return code: 0 10:48:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:22 INFO - https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:48:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:48:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:48:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:48:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:48:23 INFO - Downloaded 10840145 bytes. 10:48:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:48:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 10:48:24 INFO - Return code: 0 10:48:24 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')]} 10:48:24 INFO - https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 10:48:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:48:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:48:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 10:48:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 10:48:27 INFO - Downloaded 114175355 bytes. 10:48:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:48:27 INFO - mkdir: /builds/slave/test/properties 10:48:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:48:27 INFO - Writing to file /builds/slave/test/properties/build_url 10:48:27 INFO - Contents: 10:48:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:48:28 INFO - Running post-action listener: _resource_record_post_action 10:48:28 INFO - Running post-action listener: set_extra_try_arguments 10:48:28 INFO - ##### 10:48:28 INFO - ##### Running populate-webroot step. 10:48:28 INFO - ##### 10:48:28 INFO - Running pre-action listener: _resource_record_pre_action 10:48:28 INFO - Running main action method: populate_webroot 10:48:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:48:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:48:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:48:28 INFO - '--tppagecycles', 10:48:28 INFO - '7'], 10:48:28 INFO - 'tests': ['tsvgm']}}, 10:48:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:48:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:48:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:48:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:48:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:48:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:48:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:28 INFO - 'talos_options': ['--disable-e10s'], 10:48:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:48:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:48:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:28 INFO - 'talos_options': ['--disable-e10s'], 10:48:28 INFO - 'tests': ['damp', 'tps']}, 10:48:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:28 INFO - 'tests': ['damp', 'tps']}, 10:48:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:48:28 INFO - 'tests': ['dromaeo_dom']}, 10:48:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:48:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:48:28 INFO - 'tests': ['a11yr', 10:48:28 INFO - 'ts_paint', 10:48:28 INFO - 'tpaint', 10:48:28 INFO - 'sessionrestore', 10:48:28 INFO - 'sessionrestore_no_auto_restore', 10:48:28 INFO - 'tabpaint']}, 10:48:28 INFO - 'other-e10s': {'tests': ['a11yr', 10:48:28 INFO - 'ts_paint', 10:48:28 INFO - 'tpaint', 10:48:28 INFO - 'sessionrestore', 10:48:28 INFO - 'sessionrestore_no_auto_restore', 10:48:28 INFO - 'tabpaint']}, 10:48:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:48:28 INFO - 'ts_paint', 10:48:28 INFO - 'tpaint', 10:48:28 INFO - 'sessionrestore', 10:48:28 INFO - 'sessionrestore_no_auto_restore', 10:48:28 INFO - 'tabpaint']}, 10:48:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:48:28 INFO - 'ts_paint', 10:48:28 INFO - 'tpaint', 10:48:28 INFO - 'sessionrestore', 10:48:28 INFO - 'sessionrestore_no_auto_restore', 10:48:28 INFO - 'tabpaint']}, 10:48:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:48:28 INFO - 'tests': ['a11yr', 10:48:28 INFO - 'ts_paint', 10:48:28 INFO - 'tpaint', 10:48:28 INFO - 'sessionrestore', 10:48:28 INFO - 'sessionrestore_no_auto_restore', 10:48:28 INFO - 'tabpaint']}, 10:48:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:48:28 INFO - 'tests': ['a11yr', 10:48:28 INFO - 'ts_paint', 10:48:28 INFO - 'tpaint', 10:48:28 INFO - 'sessionrestore', 10:48:28 INFO - 'sessionrestore_no_auto_restore', 10:48:28 INFO - 'tabpaint']}, 10:48:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:48:28 INFO - 'tests': ['tsvgx', 10:48:28 INFO - 'tsvgr_opacity', 10:48:28 INFO - 'tart', 10:48:28 INFO - 'tscrollx', 10:48:28 INFO - 'cart']}, 10:48:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:48:28 INFO - 'tsvgr_opacity', 10:48:28 INFO - 'tart', 10:48:28 INFO - 'tscrollx', 10:48:28 INFO - 'cart']}, 10:48:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:28 INFO - 'talos_options': ['--disable-e10s'], 10:48:28 INFO - 'tests': ['tp5o']}, 10:48:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:28 INFO - 'tests': ['tp5o']}, 10:48:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:28 INFO - 'talos_options': ['--disable-e10s', 10:48:28 INFO - '--xperf_path', 10:48:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:48:28 INFO - 'tests': ['tp5n']}, 10:48:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:28 INFO - 'talos_options': ['--xperf_path', 10:48:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:48:28 INFO - 'tests': ['tp5n']}}, 10:48:28 INFO - 'talos.zip': {'path': '', 10:48:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:48:28 INFO - Running post-action listener: _resource_record_post_action 10:48:28 INFO - ##### 10:48:28 INFO - ##### Running create-virtualenv step. 10:48:28 INFO - ##### 10:48:28 INFO - Running pre-action listener: _resource_record_pre_action 10:48:28 INFO - Running main action method: create_virtualenv 10:48:28 INFO - Creating virtualenv /builds/slave/test/build/venv 10:48:28 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 10:48:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:48:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:48:28 INFO - Using real prefix '/tools/python27' 10:48:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:48:29 INFO - Installing distribute.............................................................................................................................................................................................done. 10:48:32 INFO - Installing pip.................done. 10:48:32 INFO - Return code: 0 10:48:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:48:32 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')]} 10:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:32 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')]} 10:48:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:32 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 0x10fb39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb49420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cca4a4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d8e00>, '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 0x10f841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:48:32 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 10:48:32 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 10:48:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 10:48:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:32 INFO - 'HOME': '/Users/cltbld', 10:48:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:32 INFO - 'LOGNAME': 'cltbld', 10:48:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:32 INFO - 'MOZ_NO_REMOTE': '1', 10:48:32 INFO - 'NO_EM_RESTART': '1', 10:48:32 INFO - 'PAGER': '/bin/cat', 10:48:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:32 INFO - 'PWD': '/builds/slave/test', 10:48:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:32 INFO - 'SHELL': '/bin/bash', 10:48:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 10:48:32 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 10:48:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:32 INFO - 'USER': 'cltbld', 10:48:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:32 INFO - 'XPC_FLAGS': '0x0', 10:48:32 INFO - 'XPC_SERVICE_NAME': '0', 10:48:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:32 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 10:48:32 INFO - Cleaning up... 10:48:32 INFO - Return code: 0 10:48:32 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 10:48:32 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')]} 10:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:32 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')]} 10:48:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:32 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fb39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb49420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cca4a4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d8e00>, '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 0x10f841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:48:32 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>=0.7.1'] in /builds/slave/test/build 10:48:32 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>=0.7.1 10:48:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 10:48:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:32 INFO - 'HOME': '/Users/cltbld', 10:48:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:32 INFO - 'LOGNAME': 'cltbld', 10:48:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:32 INFO - 'MOZ_NO_REMOTE': '1', 10:48:32 INFO - 'NO_EM_RESTART': '1', 10:48:32 INFO - 'PAGER': '/bin/cat', 10:48:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:32 INFO - 'PWD': '/builds/slave/test', 10:48:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:32 INFO - 'SHELL': '/bin/bash', 10:48:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 10:48:32 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 10:48:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:32 INFO - 'USER': 'cltbld', 10:48:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:32 INFO - 'XPC_FLAGS': '0x0', 10:48:32 INFO - 'XPC_SERVICE_NAME': '0', 10:48:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:32 INFO - Downloading/unpacking psutil>=0.7.1 10:48:32 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 10:48:32 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 10:48:32 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 10:48:32 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 10:48:32 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 10:48:32 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 10:48:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:48:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:48:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:48:35 INFO - Installing collected packages: psutil 10:48:35 INFO - Running setup.py install for psutil 10:48:35 INFO - building 'psutil._psutil_osx' extension 10:48:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:48:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:48:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:48:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:48:36 INFO - building 'psutil._psutil_posix' extension 10:48:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:48:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:48:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:48:36 INFO - ^ 10:48:36 INFO - 1 warning generated. 10:48:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:48:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:48:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:48:36 INFO - Successfully installed psutil 10:48:36 INFO - Cleaning up... 10:48:36 INFO - Return code: 0 10:48:36 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 10:48:36 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')]} 10:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:36 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')]} 10:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:36 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fb39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb49420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cca4a4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d8e00>, '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 0x10f841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:48:36 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.0.0'] in /builds/slave/test/build 10:48:36 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.0.0 10:48:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 10:48:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:36 INFO - 'HOME': '/Users/cltbld', 10:48:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:36 INFO - 'LOGNAME': 'cltbld', 10:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:36 INFO - 'MOZ_NO_REMOTE': '1', 10:48:36 INFO - 'NO_EM_RESTART': '1', 10:48:36 INFO - 'PAGER': '/bin/cat', 10:48:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:36 INFO - 'PWD': '/builds/slave/test', 10:48:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:36 INFO - 'SHELL': '/bin/bash', 10:48:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 10:48:36 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 10:48:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:36 INFO - 'USER': 'cltbld', 10:48:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:36 INFO - 'XPC_FLAGS': '0x0', 10:48:36 INFO - 'XPC_SERVICE_NAME': '0', 10:48:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:36 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:48:36 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 10:48:36 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 10:48:36 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 10:48:36 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 10:48:36 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 10:48:36 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 10:48:39 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:48:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:48:39 INFO - Installing collected packages: mozsystemmonitor 10:48:39 INFO - Running setup.py install for mozsystemmonitor 10:48:39 INFO - Successfully installed mozsystemmonitor 10:48:39 INFO - Cleaning up... 10:48:39 INFO - Return code: 0 10:48:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:48:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fb39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb49420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cca4a4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d8e00>, '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 0x10f841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:48:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:48:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:48:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 10:48:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:39 INFO - 'HOME': '/Users/cltbld', 10:48:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:39 INFO - 'LOGNAME': 'cltbld', 10:48:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:39 INFO - 'MOZ_NO_REMOTE': '1', 10:48:39 INFO - 'NO_EM_RESTART': '1', 10:48:39 INFO - 'PAGER': '/bin/cat', 10:48:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:39 INFO - 'PWD': '/builds/slave/test', 10:48:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:39 INFO - 'SHELL': '/bin/bash', 10:48:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 10:48:39 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 10:48:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:39 INFO - 'USER': 'cltbld', 10:48:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:39 INFO - 'XPC_FLAGS': '0x0', 10:48:39 INFO - 'XPC_SERVICE_NAME': '0', 10:48:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:39 INFO - Downloading/unpacking blobuploader==1.2.4 10:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:42 INFO - Downloading blobuploader-1.2.4.tar.gz 10:48:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:48:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:48:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:48:43 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 10:48:43 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 10:48:43 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 10:48:43 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 10:48:43 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 10:48:43 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 10:48:43 INFO - Downloading docopt-0.6.1.tar.gz 10:48:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:48:43 INFO - Installing collected packages: blobuploader, requests, docopt 10:48:43 INFO - Running setup.py install for blobuploader 10:48:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:48:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:48:43 INFO - Running setup.py install for requests 10:48:44 INFO - Running setup.py install for docopt 10:48:44 INFO - Successfully installed blobuploader requests docopt 10:48:44 INFO - Cleaning up... 10:48:44 INFO - Return code: 0 10:48:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48: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')]} 10:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48: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')]} 10:48:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:44 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 0x10fb39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb49420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cca4a4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d8e00>, '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 0x10f841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:48:44 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 10:48:44 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 10:48:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 10:48:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:44 INFO - 'HOME': '/Users/cltbld', 10:48:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:44 INFO - 'LOGNAME': 'cltbld', 10:48:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:44 INFO - 'MOZ_NO_REMOTE': '1', 10:48:44 INFO - 'NO_EM_RESTART': '1', 10:48:44 INFO - 'PAGER': '/bin/cat', 10:48:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:44 INFO - 'PWD': '/builds/slave/test', 10:48:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:44 INFO - 'SHELL': '/bin/bash', 10:48:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 10:48:44 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 10:48:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:44 INFO - 'USER': 'cltbld', 10:48:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:44 INFO - 'XPC_FLAGS': '0x0', 10:48:44 INFO - 'XPC_SERVICE_NAME': '0', 10:48:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:48:44 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-bPqgWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:48:44 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-c1PpJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:48:44 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-TGzVxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:48:45 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ErIpqz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:48:45 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-FRjPZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:48:45 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Bdc7zM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:48:45 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-1Y7_0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:48:45 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-N8sChW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:48:45 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-H2IKvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:48:45 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-y2ewg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:48:45 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-GRqFrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:48:45 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ZoWRt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:48:45 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-99GuXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:48:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-iXAIiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-smcqUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:48:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-tzw_Y1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:48:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-c_GHke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:48:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:48:46 INFO - Running setup.py install for manifestparser 10:48:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:48:46 INFO - Running setup.py install for mozcrash 10:48:46 INFO - Running setup.py install for mozdebug 10:48:46 INFO - Running setup.py install for mozdevice 10:48:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:48:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:48:47 INFO - Running setup.py install for mozfile 10:48:47 INFO - Running setup.py install for mozhttpd 10:48:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:48:47 INFO - Running setup.py install for mozinfo 10:48:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:48:47 INFO - Running setup.py install for mozInstall 10:48:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:48:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:48:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:48:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:48:47 INFO - Running setup.py install for mozleak 10:48:47 INFO - Running setup.py install for mozlog 10:48:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:48:47 INFO - Running setup.py install for moznetwork 10:48:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:48:48 INFO - Running setup.py install for mozprocess 10:48:48 INFO - Running setup.py install for mozprofile 10:48:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:48:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:48:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:48:48 INFO - Running setup.py install for mozrunner 10:48:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:48:48 INFO - Running setup.py install for mozscreenshot 10:48:48 INFO - Running setup.py install for moztest 10:48:48 INFO - Running setup.py install for mozversion 10:48:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:48:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:48:48 INFO - Cleaning up... 10:48:49 INFO - Return code: 0 10:48:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48: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')]} 10:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48: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')]} 10:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fb39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb49420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cca4a4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d8e00>, '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 0x10f841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:48:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:48:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:48:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 10:48:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:49 INFO - 'HOME': '/Users/cltbld', 10:48:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:49 INFO - 'LOGNAME': 'cltbld', 10:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:49 INFO - 'MOZ_NO_REMOTE': '1', 10:48:49 INFO - 'NO_EM_RESTART': '1', 10:48:49 INFO - 'PAGER': '/bin/cat', 10:48:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:49 INFO - 'PWD': '/builds/slave/test', 10:48:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:49 INFO - 'SHELL': '/bin/bash', 10:48:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 10:48:49 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 10:48:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:49 INFO - 'USER': 'cltbld', 10:48:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:49 INFO - 'XPC_FLAGS': '0x0', 10:48:49 INFO - 'XPC_SERVICE_NAME': '0', 10:48:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:48:49 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-sO9ftr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:48:49 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-nno2ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:48:49 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ZHTzIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:48:49 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ecAdEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:48:49 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-0AmVVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:48:49 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-CiH3Mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:48:49 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-avwg36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:48:50 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)) 10:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:48:50 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-dbP3h4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:48:50 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)) 10:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:48:50 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-MGfEoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:48:50 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-KtI4pC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 10:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:48:50 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-6t2GZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:48:50 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-6kyTMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 10:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:48:50 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-N0oBCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:48:50 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-YCqvVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 10:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:50 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ealb6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:48:50 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-s7blDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:48:50 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-glGkYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:48:51 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)) 10:48:51 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)) 10:48:51 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)) 10:48:51 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)) 10:48:51 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)) 10:48:51 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)) 10:48:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:53 INFO - Downloading blessings-1.6.tar.gz 10:48:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:48:53 INFO - Installing collected packages: blessings 10:48:53 INFO - Running setup.py install for blessings 10:48:53 INFO - Successfully installed blessings 10:48:53 INFO - Cleaning up... 10:48:53 INFO - Return code: 0 10:48:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:48:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:48:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:48:54 INFO - Reading from file tmpfile_stdout 10:48:54 INFO - Current package versions: 10:48:54 INFO - blessings == 1.6 10:48:54 INFO - blobuploader == 1.2.4 10:48:54 INFO - docopt == 0.6.1 10:48:54 INFO - manifestparser == 1.1 10:48:54 INFO - mozInstall == 1.12 10:48:54 INFO - mozcrash == 0.17 10:48:54 INFO - mozdebug == 0.1 10:48:54 INFO - mozdevice == 0.48 10:48:54 INFO - mozfile == 1.2 10:48:54 INFO - mozhttpd == 0.7 10:48:54 INFO - mozinfo == 0.9 10:48:54 INFO - mozleak == 0.1 10:48:54 INFO - mozlog == 3.1 10:48:54 INFO - moznetwork == 0.27 10:48:54 INFO - mozprocess == 0.22 10:48:54 INFO - mozprofile == 0.28 10:48:54 INFO - mozrunner == 6.11 10:48:54 INFO - mozscreenshot == 0.1 10:48:54 INFO - mozsystemmonitor == 0.0 10:48:54 INFO - moztest == 0.7 10:48:54 INFO - mozversion == 1.4 10:48:54 INFO - psutil == 3.1.1 10:48:54 INFO - requests == 1.2.3 10:48:54 INFO - wsgiref == 0.1.2 10:48:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48:54 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')]} 10:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:54 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')]} 10:48:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:54 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 0x10fb39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb49420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cca4a4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d8e00>, '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 0x10f841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:48:54 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 10:48:54 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 10:48:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 10:48:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:54 INFO - 'HOME': '/Users/cltbld', 10:48:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:54 INFO - 'LOGNAME': 'cltbld', 10:48:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:54 INFO - 'MOZ_NO_REMOTE': '1', 10:48:54 INFO - 'NO_EM_RESTART': '1', 10:48:54 INFO - 'PAGER': '/bin/cat', 10:48:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:54 INFO - 'PWD': '/builds/slave/test', 10:48:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:54 INFO - 'SHELL': '/bin/bash', 10:48:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 10:48:54 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 10:48:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:54 INFO - 'USER': 'cltbld', 10:48:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:54 INFO - 'XPC_FLAGS': '0x0', 10:48:54 INFO - 'XPC_SERVICE_NAME': '0', 10:48:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:54 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)) 10:48:54 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)) 10:48:54 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)) 10:48:54 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)) 10:48:54 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)) 10:48:54 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)) 10:48:54 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)) 10:48:54 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)) 10:48:54 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)) 10:48:54 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)) 10:48:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:48:54 INFO - Cleaning up... 10:48:54 INFO - Return code: 0 10:48:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:48:54 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')]} 10:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:54 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')]} 10:48:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:54 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 0x10fb39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb49420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cca4a4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d8e00>, '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 0x10f841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:48:54 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 10:48:54 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 10:48:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 10:48:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:54 INFO - 'HOME': '/Users/cltbld', 10:48:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:54 INFO - 'LOGNAME': 'cltbld', 10:48:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:54 INFO - 'MOZ_NO_REMOTE': '1', 10:48:54 INFO - 'NO_EM_RESTART': '1', 10:48:54 INFO - 'PAGER': '/bin/cat', 10:48:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:54 INFO - 'PWD': '/builds/slave/test', 10:48:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:54 INFO - 'SHELL': '/bin/bash', 10:48:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 10:48:54 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 10:48:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:54 INFO - 'USER': 'cltbld', 10:48:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:54 INFO - 'XPC_FLAGS': '0x0', 10:48:54 INFO - 'XPC_SERVICE_NAME': '0', 10:48:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:54 INFO - Downloading/unpacking jsonschema 10:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:48:57 INFO - Downloading/unpacking functools32 (from jsonschema) 10:48:57 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 10:48:57 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 10:48:57 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 10:48:57 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 10:48:57 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 10:48:57 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 10:48:57 INFO - Downloading functools32-3.2.3-2.tar.gz 10:48:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:48:57 INFO - warning: no files found matching '*.txt' 10:48:57 INFO - no previously-included directories found matching 'build' 10:48:57 INFO - no previously-included directories found matching 'dist' 10:48:57 INFO - no previously-included directories found matching '.git*' 10:48:57 INFO - Installing collected packages: jsonschema, functools32 10:48:57 INFO - Running setup.py install for functools32 10:48:57 INFO - warning: no files found matching '*.txt' 10:48:57 INFO - no previously-included directories found matching 'build' 10:48:57 INFO - no previously-included directories found matching 'dist' 10:48:57 INFO - no previously-included directories found matching '.git*' 10:48:57 INFO - Successfully installed jsonschema functools32 10:48:57 INFO - Cleaning up... 10:48:57 INFO - Return code: 0 10:48:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fb39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb49420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cca4a4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d8e00>, '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 0x10f841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 10:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 10:48:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 10:48:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:57 INFO - 'HOME': '/Users/cltbld', 10:48:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:57 INFO - 'LOGNAME': 'cltbld', 10:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:57 INFO - 'MOZ_NO_REMOTE': '1', 10:48:57 INFO - 'NO_EM_RESTART': '1', 10:48:57 INFO - 'PAGER': '/bin/cat', 10:48:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:57 INFO - 'PWD': '/builds/slave/test', 10:48:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:57 INFO - 'SHELL': '/bin/bash', 10:48:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 10:48:57 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 10:48:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:57 INFO - 'USER': 'cltbld', 10:48:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:57 INFO - 'XPC_FLAGS': '0x0', 10:48:57 INFO - 'XPC_SERVICE_NAME': '0', 10:48:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:58 INFO - Downloading/unpacking flake8 10:48: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 10:48: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 10:48: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 10:48: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 10:48: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 10:48: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 10:49:00 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:49:00 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:49:00 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 10:49:00 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 10:49:00 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 10:49:00 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 10:49:00 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 10:49:00 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 10:49:00 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:49:00 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:49:00 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 10:49:00 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 10:49:00 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 10:49:00 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 10:49:00 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 10:49:00 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 10:49:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:49:01 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 10:49:01 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 10:49:01 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 10:49:01 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 10:49:01 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 10:49:01 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 10:49:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:49:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:49:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:49:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:49:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:49:01 INFO - no previously-included directories found matching 'docs/_build' 10:49:01 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:49:01 INFO - Running setup.py install for pep8 10:49:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:49:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:49:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:49:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:49:02 INFO - no previously-included directories found matching 'docs/_build' 10:49:02 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:49:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:49:02 INFO - Cleaning up... 10:49:02 INFO - Return code: 0 10:49:02 INFO - Running post-action listener: _resource_record_post_action 10:49:02 INFO - Running post-action listener: _start_resource_monitoring 10:49:02 INFO - Starting resource monitoring. 10:49:02 INFO - ##### 10:49:02 INFO - ##### Running install step. 10:49:02 INFO - ##### 10:49:02 INFO - Running pre-action listener: _resource_record_pre_action 10:49:02 INFO - Running main action method: install 10:49:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:49:02 INFO - Reading from file tmpfile_stdout 10:49:02 INFO - Detecting whether we're running mozinstall >=1.0... 10:49:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:49:02 INFO - Reading from file tmpfile_stdout 10:49:02 INFO - Output received: 10:49:02 INFO - Usage: mozinstall [options] installer 10:49:02 INFO - Options: 10:49:02 INFO - -h, --help show this help message and exit 10:49:02 INFO - -d DEST, --destination=DEST 10:49:02 INFO - Directory to install application into. [default: 10:49:02 INFO - "/builds/slave/test"] 10:49:02 INFO - --app=APP Application being installed. [default: firefox] 10:49:02 INFO - mkdir: /builds/slave/test/build/application 10:49:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 10:49:34 INFO - Reading from file tmpfile_stdout 10:49:34 INFO - Output received: 10:49:34 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:49:34 INFO - Running post-action listener: _resource_record_post_action 10:49:34 INFO - ##### 10:49:34 INFO - ##### Running run-tests step. 10:49:34 INFO - ##### 10:49:34 INFO - Running pre-action listener: _resource_record_pre_action 10:49:34 INFO - Running main action method: run_tests 10:49:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:49:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:49:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:49:34 INFO - 2016-06-06 10:49:34.477 screenresolution[1937:11815] starting screenresolution argv=screenresolution get 10:49:34 INFO - 2016-06-06 10:49:34.479 screenresolution[1937:11815] Display 0: 1600x1200x32@60 10:49:34 INFO - 2016-06-06 10:49:34.485 screenresolution[1938:11817] starting screenresolution argv=screenresolution list 10:49:34 INFO - Available Modes on Display 0 10:49:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:49:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:49:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:49:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:49:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:49:34 INFO - Intel Iris: 10:49:34 INFO - Chipset Model: Intel Iris 10:49:34 INFO - Type: GPU 10:49:34 INFO - Bus: Built-In 10:49:34 INFO - VRAM (Dynamic, Max): 1536 MB 10:49:34 INFO - Vendor: Intel (0x8086) 10:49:34 INFO - Device ID: 0x0a2e 10:49:34 INFO - Revision ID: 0x0009 10:49:34 INFO - Displays: 10:49:34 INFO - Display: 10:49:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:49:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:49:34 INFO - Display Serial Number: 1600x1200 60 10:49:34 INFO - Main Display: Yes 10:49:34 INFO - Mirror: Off 10:49:34 INFO - Online: Yes 10:49:34 INFO - Rotation: Supported 10:49:34 INFO - Return code: 0 10:49:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:49:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:49:34 INFO - Python 2.7.3 10:49:34 INFO - Return code: 0 10:49:34 INFO - grabbing minidump binary from tooltool 10:49:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:49:34 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d8e00>, '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 0x10f841db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9d3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:49:34 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:49:34 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:49:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:49:34 INFO - Return code: 0 10:49:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:49:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:49:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:49:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:49:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:49:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:49:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:49:35 INFO - Return code: 0 10:49:35 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--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 10:49:35 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0025 --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 10:49:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render', 10:49:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:35 INFO - 'HOME': '/Users/cltbld', 10:49:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:35 INFO - 'LOGNAME': 'cltbld', 10:49:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:49:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:35 INFO - 'MOZ_NO_REMOTE': '1', 10:49:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:49:35 INFO - 'NO_EM_RESTART': '1', 10:49:35 INFO - 'PAGER': '/bin/cat', 10:49:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:35 INFO - 'PWD': '/builds/slave/test', 10:49:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:49:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:35 INFO - 'SHELL': '/bin/bash', 10:49:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners', 10:49:35 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 10:49:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:35 INFO - 'USER': 'cltbld', 10:49:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:35 INFO - 'XPC_FLAGS': '0x0', 10:49:35 INFO - 'XPC_SERVICE_NAME': '0', 10:49:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:35 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--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 10:49:36 INFO - mozversion application_buildid: 20160606053329 10:49:36 INFO - mozversion application_changeset: 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 10:49:36 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:49:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:49:36 INFO - mozversion application_name: Firefox 10:49:36 INFO - mozversion application_remotingname: firefox-dev 10:49:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:49:36 INFO - mozversion application_vendor: Mozilla 10:49:36 INFO - mozversion application_version: 48.0a2 10:49:36 INFO - mozversion platform_buildid: 20160606053329 10:49:36 INFO - mozversion platform_changeset: 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 10:49:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:49:36 INFO - mozversion platform_version: 48.0a2 10:49:36 INFO - using testdate: 1465235376 10:49:36 INFO - actual date: 1465235376 10:49:36 INFO - starting webserver on 'localhost:49674' 10:49:36 INFO - SUITE-START | Running 2 tests 10:49:36 INFO - TEST-START | dromaeo_css 10:49:36 INFO - operating with platform_type : mac_ 10:49:36 INFO - Initialising browser for dromaeo_css test... 10:49:36 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpNkOljj/profile http://localhost:49674/getInfo.html) 10:49:38 INFO - PROCESS | 1956 | __metrics Screen width/height:1600/1200 10:49:38 INFO - PROCESS | 1956 | colorDepth:24 10:49:38 INFO - PROCESS | 1956 | Browser inner width/height: 1024/689 10:49:38 INFO - PROCESS | 1956 | __metrics 10:49:38 INFO - PROCESS | 1956 | 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" 10:49:38 ERROR - PROCESS | 1956 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:49:39 INFO - TEST-INFO | 1956: exit 0 10:49:39 INFO - Browser initialized. 10:49:39 INFO - Running cycle 1/1 for dromaeo_css test... 10:49:39 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpNkOljj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:49:51 INFO - PROCESS | 1958 | RSS: Main: 211607552 10:49:51 INFO - PROCESS | 1958 | 10:50:42 INFO - PROCESS | 1958 | 1465235442547 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] 10:52:53 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:49674/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49674/tests/dromaeo/cssquery-ext.html) 10:52:54 INFO - PROCESS | 1958 | RSS: Main: 327311360 10:52:54 INFO - PROCESS | 1958 | 10:55:57 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:49674/tests/dromaeo/cssquery-ext.html (next: http://localhost:49674/tests/dromaeo/cssquery-jquery.html) 10:55:57 INFO - PROCESS | 1958 | RSS: Main: 349868032 10:55:57 INFO - PROCESS | 1958 | 10:58:59 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:49674/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49674/tests/dromaeo/cssquery-mootools.html) 10:59:00 INFO - PROCESS | 1958 | RSS: Main: 359313408 10:59:00 INFO - PROCESS | 1958 | 11:02:02 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:49674/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49674/tests/dromaeo/cssquery-prototype.html) 11:02:03 INFO - PROCESS | 1958 | RSS: Main: 354357248 11:02:03 INFO - PROCESS | 1958 | 11:05:06 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:49674/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49674/tests/dromaeo/cssquery-yui.html) 11:05:06 INFO - PROCESS | 1958 | RSS: Main: 319860736 11:05:06 INFO - PROCESS | 1958 | 11:08:09 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:49674/tests/dromaeo/cssquery-yui.html (next: http://localhost:49674/tests/dromaeo/cssquery-dojo.html) 11:08:09 INFO - PROCESS | 1958 | RSS: Main: 364359680 11:08:09 INFO - PROCESS | 1958 | 11:08:09 INFO - PROCESS | 1958 | __start_tp_report 11:08:09 INFO - PROCESS | 1958 | _x_x_mozilla_page_load 11:08:09 INFO - PROCESS | 1958 | _x_x_mozilla_page_load_details 11:08:09 INFO - PROCESS | 1958 | |i|pagename|runs| 11:08:09 INFO - PROCESS | 1958 | |0;dojo.html;3347.3138006708627;3433.1588760753634;3435.2614187949584;3450.84471198796;3495.161161321282;5660.29246344207;5666.235058267132;5667.093265077583;5677.120046392805;5682.374938756739;5169.630048037273;5289.259503669489;5658.066419040863;5710.14347847822;5712.086066229404;5152.639315247934;5162.638615296931;5222.817201397952;5265.3155089838265;5307.840764777063;4382.364557139213;5138.743062846861;5267.183586544073;5272.919051594414;5273.630845840793;5166.741662916858;5224.425313215544;5247.86880327991;5248.186531087668;5261.5264626183825;5226.529612334889;5271.446498117681;5284.207368894658;5284.6564973277;5317.388500322435;5111.565516931066;5143.305653736773;5166.793328266865;5220.817271395521;5244.475552444763;5505.421930697259;5527.060399732055;5549.417311182308;5549.805756798533;5565.0817615093465;6174.691265436755;6202.472789812871;6226.159468471744;6235;6235.968820155916;6057.922415208467;6179.474744646711;6238.688065596702;6270.247570291549;6291.056341548759;5769.509591684669;5777.711114444261;5779.133130030529;5780.219670344533;5798.739056742456;6262.3111457739615;6290;6291.496680265568;6296.937030629727;6306.274778400542;6031.185789918392;6089.086637004396;6115.331611965994;6156.846078847975;6162.352952939967;6256.718447669866;6257.687115644199;6272.498200143978;6272.780452684134;6280.057991301342;6180;6217.6891155442045;6222.751089956441;6226.719797609118;6237.064440333987;6094.908576371358;6164.6917654117115;6164.845878853064;6167.506599472032;6234.189555357865;4841.806327746851;4895.996320754308;4902.411710594752;4983.476734942851;4991.550760431506;4409.096135292258;4420.403245561873;4420.535843736361;4429.490608580055;4469.776511174428;5322.680639161663;5487.75305111271;5681.715914204273;5708.086706127;5741.339745929188;6033.3061697905305;6158.076288556752;6220.222472190976;6232.906506402408;6251.593646412968;5626.774929002876;5633.436656334416;5643.858903527444;5644.463775941279;5661.688607126647;5374;5385.3268341457315;5386.467049753029;5393.056215162331;5437.8368648940595;6223.688815559204;6269.153664255267;6323.051542268697;6329.810105696836;6335.176427064452;6207.875842483035;6252.343503932022;6253;6272.466840318624;6278.497720182466;6296.307406185408;6313.715882785194;6314.273858506147;6336.144620476177;6348.746050158126;5990.610610310316;6097.695115244309;6129.969350153221;6140;6140.293866205265;6133.26400831903;6147.231596050494;6208.689565521796;6210;6228.532860035425;6118.694065296806;6136.140940268277;6157.384261573807;6158.784442544489;6164.6917654118015;6076.361981991828;6147.200863887666;6150.7539698411565;6152.354002829638;6160.353162917829;4998;5799.014167591436;5893;5956.106584012433;5983.222181116601;5982.162497250475;6075.058365953284;6099.420555047263;6105.262111899988;6122;5803;5867.471927526543;5913.2608423947;5915.378885217162;5928.762849486145 11:08:09 INFO - PROCESS | 1958 | |1;ext.html;514.8738559053032;519.4831143012702;536.975836087376;561.1610642090075;578.0289114287995;30775.76854430286;38279.42580861286;39401.21197576047;39431.605683943155;39488.80255598718;41533.792331038305;42029.949251268736;42085.789571052104;42097;42209.788951055205;8387.706430274942;9453.10195531425;9660.565274562627;9666.27502937279;9671.645969564232;8523.914760852405;8638.481691098523;8641.092685268051;11524.769504609903;11828.349440780798;11479.311241325506;11564.768704625902;11566.132540059487;11566.884331156707;11571.710707232345;11526.19316647835;11556.711082222928;11557.711057223552;11562;11569.016633586156;8505.617247223829;8717.041125476193;8718;8726.738197854074;10628.893711062869;38302.42546361779;38855.41716874249;38943.805280973414;39044;39064.414033789515;3143.654198038214;3281.5898012748407;3289.0626171540994;3501.719862411001;3720.776753394805;26019;27378.863105684344;27867;27911.883524658988;28101;2677.678678558566;2708.2010806812036;2718.4834881372476;2723.2340518678075;2850.828950262991;381347;381749.09125454474;382181.08909455553;382863;383574;59528;59968.40031599628;60080.699596502614;60123.39876601178;60221.09668354891;63405.04892426617;63560;63712.362876370644;63735.362646372945;63743.681281594225;15019.774703379459;17267.74098388525;17449.912750436168;17456.738148927776;17464.039477828588;11541.884581154081;11589.826152607717;11603.825942610867;11617.244879082833;13410;15408.998415103206;15644.296006679499;15654;15661.765073523906;15732.842671573366;6951.304869513109;7101.538400004087;7134.286571342928;7145.571265724073;7186.784396468114;986.5757724178673;998.7453199434098;1030.8299130643509;1099.7140743406771;1114.6098865397207;29218.977335793144;29724;29814.701852981194;30066.54900176499;30273.848630756704;1035.3632516002604;1042.9883013476915;1108.4900945564968;1122.320995797547;1182.2079206931376;15079.170645614597;15106;15106.244687765568;15488.922555387377;15547.689046219239;576212;581015;581622;581969;583994;59717;59858.102128468105;60000.39999600036;60019.699901500215;60028;1457.7157454296523;1542.2982542942934;1550.99224503879;1556.968860622804;1559.7582374731935;24545.63181552314;28802.711972880843;29581.26046848846;29741;30150.69849301479;48026;48341.758291208316;48508.787280318276;48514.75742621264;48572;437947;439720;440833;441958;442432;32777.83611081929;39052.82841514759;39353.80323098366;40079.39880901847;40873.795631021654;42414;42515;42792.572074280106;43594.12811743811;43908.560914390444;34892.30215395729;35494.112647184025;36833.63166368302;36954.815225923696;37262.06844828901;32341.838290808395;34093.8067167648;36435.27129457449;36520.26959460743;37212.44181337336;36754.63245367619;36761.448578270814;36924.630753692116;37244.6275537252;37314.06714832151;25807.741922581285;30189.698103018687;31502.36995260128;32741.83629081839;38577;30003.849980749957;30242;31036.534451982785;31247;31905 11:08:09 INFO - PROCESS | 1958 | |2;jquery.html;401.5923837305136;401.8633664554051;402.1675132475775;404.1230529750441;420.30229818501294;19255.518612034706;19667.5083122922;20330.796692033113;20331.39005829824;20358.185672573098;19596.608067838635;20017.899910500462;20730;21461.034253458594;21472.570548589018;4877.82927597535;5917.497012753921;6124.356942521015;6135.6625385603775;6202.162708034425;6027.789027384042;6112.602680825732;6116.143739876398;6122.510199184077;6133.2026836511195;5816.476517113459;6060.2424696912885;6079.05774604937;6120.1125836753645;6128.417800308964;5978.342382337939;6016.879662406749;6030.185924900126;6035.245594300713;6042.093685947099;5173.689578625294;5222.634415590872;5306.965141797351;5314.867128321785;5341;26314.60528092061;26471;26677.733222667717;26869.462610747676;26912.73087269122;1975.6345076160956;2084.4267826347723;2104.989475052615;2184.17001539414;2754.228815931542;9552.665656701982;10273.537690803932;12052.578159764455;14715.969882108257;15261.313240904075;1754.9122543872754;1799.379214171109;1820.9635807283912;1844.9538761530935;1845.0866820923595;107258.92741072568;111257;117830.41084794607;123245.38377308146;125241.22346541873;11735.4132293385;11775.116866234895;11811.88188118125;11891.524339026515;11933.940330298294;15194.468193613171;15207.695846083016;15209.315580798891;15230;15256.389744410099;8484.490930544187;8883.37816352862;8900.198982091513;8984.056674049261;9072.228860546795;8897.11028897118;8939.284857211407;8963.282937364995;9028.458292502472;9037.819243615222;4621.838235661736;4761.119357853629;4761.809527618856;4783.330333753277;4825.5174482551465;1961.8920959347372;2018.162462578043;2039.184326269504;2062.658882168749;2064.5870825834895;785.7407055671615;866.6013633728429;990.2127808392345;1021.7190272675074;1023.048564834696;19681.90159049224;21352.893235533724;22372.32883013512;22405.991730372174;22413.327600171695;936.1060187520901;959.960084819758;974.8878878928872;989.5794287427815;999.1415966302816;10731.195160363004;11032.227744058;11046.447677616086;11068.335899846035;11077;512882;546550;626172;638473;642846;136111.8749656248;136214;136713.94929075873;138342.30828845792;143167.85248221285;1066.4134725900724;1072.463768115942;1086.527360598158;1087.8205096159204;1088.9346639201515;18231.345364726887;18346.990915499777;18578.628427431646;18687;18815.435536933914;54186.91626167376;55163.724181378835;55220.447795523134;55588;56227.43772562222;79703;79845;80588;83187.58406208172;84784.57607712169;8182.590870456573;8209.548474833702;8214.067187010227;8320.958395207985;8331.875021874555;9319;12051.096167787626;12222.449989750661;12307;12654;10643.787124257626;10754.731131721457;10976.890231097586;10994.34033957965;11064.612738554433;10452.738681533023;10489.318194317346;10854.18593605467;10863.837042444527;11110.50002749862;6165.938340616536;11179.888201117885;11436.714082148013;11581.826272606086;11664.533418663159;8137.308328792119;8171.632276547451;8218.671253149807;8344.749657510285;8531;8175.018997720312;10030.49847507607;10966.945165274123;11259.549618015188;11316.660500185008 11:08:09 INFO - PROCESS | 1958 | |3;mootools.html;1423.7721964485672;1553.9145347005913;1571.7249481340766;1807.4848668129578;1955.3254127326068;7737.303642672158;8325.542095184763;8497;8506.447080939735;8512.106228845974;8236.752897413087;8474.872876906851;8494.745157645266;8504.107068757785;8506.787330316729;2460.470998735276;2488.4525404411;2491.252624212738;2491.352248415407;2494.4886298308893;2413.2760171948435;2473.703155621328;2479.6776419065495;2481.1192026830486;2482.391814005562;2443.6701045358914;2476.2447453526665;2487.2289590226997;2487.987560062197;2489.8879550420183;2434.732179460258;2460.2127319257843;2472.3682369636044;2480.950380992388;2483.801295896324;1816.8546516278798;1957.784643689193;2426.623873299641;2441.853488790678;2444.8655323957173;7180.210176880539;7249.637518124073;7548.396128309777;7739.806504837367;8590.87113693295;4864.416270047617;4962.379702537183;4970.453250142481;5212.973935130338;5288.894222115536;3388.678075582816;3429.416999110157;3434.6221915589267;3452.844621991991;3481.547398838135;959.0649117110831;1016.466355163545;1042.7393151712072;1047.33494231163;1068.8770791358938;10876.510557024953;10927.836082458769;10933.016028557468;10952.726181845437;10954.726131846768;4675.929861052087;4716.528347165256;4743.501932297059;4749.2876068589985;4798.632389768899;9517.666881659246;9606.759831004281;9617.51912404377;9620.567074481665;9621.08599683029;5253.894922101613;5259.526642602184;5284.9735751320995;5286.286351342596;5296.92054619181;4014;4714.4106986626675;4723.811047558059;4732.361131247238;4736.360591320196;3382.9512851016266;3852.3065848147603;3855.1133239355104;3944.723869329177;3973.980130099389;1137.3801278303345;1329.4482789642384;1335.3990704183157;1337.4855272088412;1339.6249050265942;897.3045889435609;911.2072496927715;928.1043792740038;930.2883294279882;968.9563969621383;5489.917651235235;5639.689817060101;5758.481736643722;5768.423157684282;5881.911771323434;900.6622516556291;964.1659964130977;969.336004836675;971.9611215551299;975.2588033094898;3771.905702357463;3785.545734511876;3861.4593956846065;3952.071263253133;3975.3043217436834;9693.515324233902;9931.404115753077;9936.50317484123;9936.652217172365;10070;8156;9753.902460975441;9865.260105492116;9868.963758805223;9945.552450139761;7578.4316176287;8014.318782903402;8020.157883422156;8033.236842499953;8137.267645911897;5475.890482190254;5480.917786233289;5585.469380408855;5675.432456754292;5770.826875193751;9000.324975626723;9109.681161159328;9287.48918809472;9319.347645664739;9322.95338523303;8794.164554367326;9081.318901082577;9263.629454821732;9268.675596354095;9377.578008989476;7820.921790782019;7907.881381779162;7913.327367173854;7938.841223175619;7953.8409231816195;7840.490368126054;7875.527468351688;7893.17121702213;7902.565358905315;7912.28789408956;7010.228874823867;7183.138023437221;7224.385927196247;7227.38567221771;7324.08448943882;7137.750178743719;7183;7192.568445893263;7222.313880181375;7268.527545709512;6465.741370345134;6541;6545.443637290884;6550.050242714684;6552.148220731275;3954.1696243789124;3954.980225098856;4752.596029337406;4850.1997170466475;4888.193448081169;4471.217536930959;4638.953610463852;4728.290756386569;4799.66402351832;4825.734584597741 11:08:09 INFO - PROCESS | 1958 | |4;prototype.html;254.96685430893982;315.94628913084773;325.6743256743257;325.9445894197986;330.57686161713;5343.572514198864;6669.032990216422;6672.299408562104;6684.699188536521;6794.3545363190415;6421.197350331208;6497.870042599146;6645.6344901030425;6693.765718199864;6724.865502689943;5024.849254522352;5025.14572522672;5029.547340739333;5044.167712327461;5067.265246539258;5041.067402530525;5049.949500505003;5051.823186188484;5054.317667114948;5066.012127635113;4758.666893317469;4826.734529600888;4920.23736320869;4994.675346102492;5011.098002359574;4967;4971.204607262839;5001.374828146481;5016.774245158977;5019.347484826967;4292.484901811803;4299.978500107511;4302.161078589676;4302.440682711227;4305.590968857953;5235.895282094337;6458.838529036765;6471.288158302583;6480.125183100316;6494.740210391578;5813.4477224664015;5842.357340692521;5854.590178687494;5864.208331875185;5882.617629854046;6866.519343645947;7097.929020709778;7134.607596582186;7143.035690181811;7150.1777295611155;4731.124741922721;4755.976220118911;4765.11845308619;4772.642051846091;4772.80908763649;7543.69825206991;7549.0558682114015;7552.962235188844;7560.281773231534;7575.659095340723;4819.49395313494;4838.612910967114;4843.0313937211995;4851.199552073868;4854.441739200037;4922.58158056562;4942.579880710108;4942.728149951715;4946.258061290835;4949.1091603510995;5657.263854747228;5747.25285712863;5830;5831.79588714393;5848.883022339614;7147.571145731273;7187.065681461481;7202.603856787927;7204.639768011683;7210.495265331481;1578.273993962767;1593.8963967342086;1626.1543997121667;1694.5932976085896;1725.6462425203053;1050.779336339362;1067.893210678926;1075.3171735947728;1076.8061748885275;1088.6244245735209;5542.695151766611;6632.900506492407;6635.336466353326;6680.5991640501725;6698.665066746643;7406.8888966665545;7418.888716669254;7421.183669796318;7431.925680743123;7439.404847612178;6651.16860392451;6676.298988606127;6677.265500794909;6679.599224046573;6695.631740254333;3419.4357930941114;3685.723570732152;3816.179521402856;3941.6452519273403;3979.303621866162;7854.921450785419;7902.723404680809;7910.406719496061;7922.207779222032;7932.881006784903;7780.18308077655;7780.416468764865;7780.922190778133;7785.883211751829;7795.883061754078;7881.502514522337;7895.749711332242;7907.0906845712325;7908.051033875741;7929.563873986871;7197.748078817216;7422.96288518554;7425.183229844821;7436.144843343085;7511;6716.5634233774645;7339.669714862737;7359.264073592598;7367.336939675455;7372.115346158495;7854.41091918097;7860.724874629361;7868.488548244346;7895.328897043815;7903.881441778493;4660.790264438177;4716.29255611661;4718.740469274223;4720.221163507983;4728.534154412143;5851.356350801493;7128.037714908421;7458.813529661802;7458.888116678146;7463.925360746323;5793.594448388572;6792.864142717217;7242.275772422716;7244.746433875;7258.165310989092;7238.312360325971;7254.854902901807;7258.0927384076995;7264.491485595958;7276.490645654965;7073.115860517435;7083.645817709197;7093.077899872984;7094.858102838018;7116.252793456612;6876.828079297858;6922.58464492132;7206.531575447579;7206.819829504095;7261.636918154177;6861.4853885957755;6915.853286754414;6934.237233904166;6937.618430986344;6948.6178260196175 11:08:09 INFO - PROCESS | 1958 | |5;yui.html;701.344243132671;749.3181205103357;755.3428517190042;757.4773406349622;805.9884844520128;14838.258087095643;14840.925795371022;14965.551033468986;14999.625009374771;15098.622534436645;12221.572244971429;13676.794848077261;13744.381502832372;13753.174809511436;13760.862391376108;699.4753934549088;895.3463971300954;896.4397251717677;896.6278994217424;898.2320116300534;889.8932128144631;905.0949050949051;905.5743800413828;906.0486489186328;909.5906841921129;956.3926906414442;956.5408603870146;956.9186619137382;958.4345236310576;960;769.5844244108175;784.2079499705281;786.080286065305;786.5516655506335;954.3033585482567;704.387183150657;704.989425158623;707.0949185043153;707.9362857342812;711.0365454808712;14498.71002579953;14574.854251457456;14577.635559111;14587.70824583513;14616.926915365462;10699.304545204537;11865.466054027589;12265.0187984961;12497.25016499013;12716.427760750696;9825.950870245675;9850.458224797634;10155.593776248941;10180.33827801198;10301.793964120676;3504;3513.2446523997464;3545.663161999606;3578.4632305154173;3724.0689827543115;80944;82207.65650232715;83020.16979830185;83234;83374.58312708397;9262.72211833646;9299.628014879465;9341.065893410605;9355.578998945064;9538.52307384628;20475.180992760124;20567.588648226953;20572.588548228952;20582;20592.794072059387;7392.260773922672;7519.248075192437;7527.924720752722;7629.2752188542;7787.649555770004;4069.694772892045;4083.5916408358926;4137.40007698888;4221.6200541951375;4237.724547904377;3539.7699149555197;3596.1549035976523;3597.8740744074357;3600.513930619385;3601.4237721964882;647.6470323673609;665.9467242620676;670.5205777868749;670.8222321084917;683.0198664915862;550.9752061157258;580.9186713860063;608.0726891490513;611.9602225855305;613.9017757158833;7686.423518236043;7979;8046.994125734283;8180.83638327242;8296.502209867427;507.7994192294023;580.9390014048548;591.3465620489297;600.8768202518473;604.2869414091414;3755.9436608450897;3758.1168425420005;3775.188334508039;3908.726389152732;3954.4661470701662;90923.09076909146;92049;92145;92546.53726731324;92707;55131.17303240455;77242;78115.2188478108;78946.60526697445;80399.59800200962;68839.65580172167;69385.30614693889;69470.95793563101;69509.65245173742;69511.65244173747;7462.202323016054;7596.962015189889;7759.573223472763;7763.340116090196;7777.261160189773;48409.7897552564;49075.11098668183;49628;49674;50655.493445065076;65989.34010659832;71305;71548;71552.5689486197;72356.27643723495;9559.952200239233;9774;9778.75553111178;10004.149647280063;10067.546960386653;10256.692299231036;10486.108680762221;10617.044465998097;10618.946905265424;10711.410872402093;9421.85867212006;9944.602215911282;10133.240006999356;10171.949140254252;10367.429791361548;9391.107844754737;10040.146587539848;10090.646827361006;10235.385876847413;10235.897641023792;9822.312438129547;9919.950400247953;9927.255455840696;10014.599416023277;10120.797584048425;8555.275254514583;8707.521086340312;8742.562871856255;8874.378793484653;8903.910960890309;8611.13888611134;8651.783705407415;8670.91329086701;8744.912550874664;8795.516246606498 11:08:09 INFO - PROCESS | 1958 | __end_tp_report 11:08:09 INFO - PROCESS | 1958 | __start_cc_report 11:08:09 INFO - PROCESS | 1958 | _x_x_mozilla_cycle_collect,1115 11:08:09 INFO - PROCESS | 1958 | __end_cc_report 11:08:09 INFO - PROCESS | 1958 | __startTimestamp1465236489888__endTimestamp 11:08:09 INFO - PROCESS | 1958 | 11:08:09 INFO - PROCESS | 1958 | ------- Summary: start ------- 11:08:09 INFO - PROCESS | 1958 | Number of tests: 6 11:08:09 INFO - PROCESS | 1958 | 11:08:09 INFO - PROCESS | 1958 | [#0] dojo.html Cycles:165 Average:5717.09 Median:5928.76 stddev:636.76 (10.7%) stddev-sans-first:610.98 11:08:09 INFO - PROCESS | 1958 | Values: 3347.3 3433.2 3435.3 3450.8 3495.2 5660.3 5666.2 5667.1 5677.1 5682.4 5169.6 5289.3 5658.1 5710.1 5712.1 5152.6 5162.6 5222.8 5265.3 5307.8 4382.4 5138.7 5267.2 5272.9 5273.6 5166.7 5224.4 5247.9 5248.2 5261.5 5226.5 5271.4 5284.2 5284.7 5317.4 5111.6 5143.3 5166.8 5220.8 5244.5 5505.4 5527.1 5549.4 5549.8 5565.1 6174.7 6202.5 6226.2 6235.0 6236.0 6057.9 6179.5 6238.7 6270.2 6291.1 5769.5 5777.7 5779.1 5780.2 5798.7 6262.3 6290.0 6291.5 6296.9 6306.3 6031.2 6089.1 6115.3 6156.8 6162.4 6256.7 6257.7 6272.5 6272.8 6280.1 6180.0 6217.7 6222.8 6226.7 6237.1 6094.9 6164.7 6164.8 6167.5 6234.2 4841.8 4896.0 4902.4 4983.5 4991.6 4409.1 4420.4 4420.5 4429.5 4469.8 5322.7 5487.8 5681.7 5708.1 5741.3 6033.3 6158.1 6220.2 6232.9 6251.6 5626.8 5633.4 5643.9 5644.5 5661.7 5374.0 5385.3 5386.5 5393.1 5437.8 6223.7 6269.2 6323.1 6329.8 6335.2 6207.9 6252.3 6253.0 6272.5 6278.5 6296.3 6313.7 6314.3 6336.1 6348.7 5990.6 6097.7 6130.0 6140.0 6140.3 6133.3 6147.2 6208.7 6210.0 6228.5 6118.7 6136.1 6157.4 6158.8 6164.7 6076.4 6147.2 6150.8 6152.4 6160.4 4998.0 5799.0 5893.0 5956.1 5983.2 5982.2 6075.1 6099.4 6105.3 6122.0 5803.0 5867.5 5913.3 5915.4 5928.8 11:08:09 INFO - PROCESS | 1958 | 11:08:09 INFO - PROCESS | 1958 | [#1] ext.html Cycles:180 Average:61702.08 Median:36939.72 stddev:126432.80 (342.3%) stddev-sans-first:126704.02 11:08:09 INFO - PROCESS | 1958 | Values: 514.9 519.5 537.0 561.2 578.0 30775.8 38279.4 39401.2 39431.6 39488.8 41533.8 42029.9 42085.8 42097.0 42209.8 8387.7 9453.1 9660.6 9666.3 9671.6 8523.9 8638.5 8641.1 11524.8 11828.3 11479.3 11564.8 11566.1 11566.9 11571.7 11526.2 11556.7 11557.7 11562.0 11569.0 8505.6 8717.0 8718.0 8726.7 10628.9 38302.4 38855.4 38943.8 39044.0 39064.4 3143.7 3281.6 3289.1 3501.7 3720.8 26019.0 27378.9 27867.0 27911.9 28101.0 2677.7 2708.2 2718.5 2723.2 2850.8 381347.0 381749.1 382181.1 382863.0 383574.0 59528.0 59968.4 60080.7 60123.4 60221.1 63405.0 63560.0 63712.4 63735.4 63743.7 15019.8 17267.7 17449.9 17456.7 17464.0 11541.9 11589.8 11603.8 11617.2 13410.0 15409.0 15644.3 15654.0 15661.8 15732.8 6951.3 7101.5 7134.3 7145.6 7186.8 986.6 998.7 1030.8 1099.7 1114.6 29219.0 29724.0 29814.7 30066.5 30273.8 1035.4 1043.0 1108.5 1122.3 1182.2 15079.2 15106.0 15106.2 15488.9 15547.7 576212.0 581015.0 581622.0 581969.0 583994.0 59717.0 59858.1 60000.4 60019.7 60028.0 1457.7 1542.3 1551.0 1557.0 1559.8 24545.6 28802.7 29581.3 29741.0 30150.7 48026.0 48341.8 48508.8 48514.8 48572.0 437947.0 439720.0 440833.0 441958.0 442432.0 32777.8 39052.8 39353.8 40079.4 40873.8 42414.0 42515.0 42792.6 43594.1 43908.6 34892.3 35494.1 36833.6 36954.8 37262.1 32341.8 34093.8 36435.3 36520.3 37212.4 36754.6 36761.4 36924.6 37244.6 37314.1 25807.7 30189.7 31502.4 32741.8 38577.0 30003.8 30242.0 31036.5 31247.0 31905.0 11:08:09 INFO - PROCESS | 1958 | 11:08:09 INFO - PROCESS | 1958 | [#2] jquery.html Cycles:180 Average:35422.69 Median:22409.66 stddev:99778.07 (445.2%) stddev-sans-first:100023.32 11:08:09 INFO - PROCESS | 1958 | Values: 401.6 401.9 402.2 404.1 420.3 19255.5 19667.5 20330.8 20331.4 20358.2 19596.6 20017.9 20730.0 21461.0 21472.6 4877.8 5917.5 6124.4 6135.7 6202.2 6027.8 6112.6 6116.1 6122.5 6133.2 5816.5 6060.2 6079.1 6120.1 6128.4 5978.3 6016.9 6030.2 6035.2 6042.1 5173.7 5222.6 5307.0 5314.9 5341.0 26314.6 26471.0 26677.7 26869.5 26912.7 1975.6 2084.4 2105.0 2184.2 2754.2 9552.7 10273.5 12052.6 14716.0 15261.3 1754.9 1799.4 1821.0 1845.0 1845.1 107258.9 111257.0 117830.4 123245.4 125241.2 11735.4 11775.1 11811.9 11891.5 11933.9 15194.5 15207.7 15209.3 15230.0 15256.4 8484.5 8883.4 8900.2 8984.1 9072.2 8897.1 8939.3 8963.3 9028.5 9037.8 4621.8 4761.1 4761.8 4783.3 4825.5 1961.9 2018.2 2039.2 2062.7 2064.6 785.7 866.6 990.2 1021.7 1023.0 19681.9 21352.9 22372.3 22406.0 22413.3 936.1 960.0 974.9 989.6 999.1 10731.2 11032.2 11046.4 11068.3 11077.0 512882.0 546550.0 626172.0 638473.0 642846.0 136111.9 136214.0 136713.9 138342.3 143167.9 1066.4 1072.5 1086.5 1087.8 1088.9 18231.3 18347.0 18578.6 18687.0 18815.4 54186.9 55163.7 55220.4 55588.0 56227.4 79703.0 79845.0 80588.0 83187.6 84784.6 8182.6 8209.5 8214.1 8321.0 8331.9 9319.0 12051.1 12222.4 12307.0 12654.0 10643.8 10754.7 10976.9 10994.3 11064.6 10452.7 10489.3 10854.2 10863.8 11110.5 6165.9 11179.9 11436.7 11581.8 11664.5 8137.3 8171.6 8218.7 8344.7 8531.0 8175.0 10030.5 10966.9 11259.5 11316.7 11:08:09 INFO - PROCESS | 1958 | 11:08:09 INFO - PROCESS | 1958 | [#3] mootools.html Cycles:180 Average:5462.27 Median:5478.40 stddev:2945.57 (53.8%) stddev-sans-first:2938.19 11:08:09 INFO - PROCESS | 1958 | Values: 1423.8 1553.9 1571.7 1807.5 1955.3 7737.3 8325.5 8497.0 8506.4 8512.1 8236.8 8474.9 8494.7 8504.1 8506.8 2460.5 2488.5 2491.3 2491.4 2494.5 2413.3 2473.7 2479.7 2481.1 2482.4 2443.7 2476.2 2487.2 2488.0 2489.9 2434.7 2460.2 2472.4 2481.0 2483.8 1816.9 1957.8 2426.6 2441.9 2444.9 7180.2 7249.6 7548.4 7739.8 8590.9 4864.4 4962.4 4970.5 5213.0 5288.9 3388.7 3429.4 3434.6 3452.8 3481.5 959.1 1016.5 1042.7 1047.3 1068.9 10876.5 10927.8 10933.0 10952.7 10954.7 4675.9 4716.5 4743.5 4749.3 4798.6 9517.7 9606.8 9617.5 9620.6 9621.1 5253.9 5259.5 5285.0 5286.3 5296.9 4014.0 4714.4 4723.8 4732.4 4736.4 3383.0 3852.3 3855.1 3944.7 3974.0 1137.4 1329.4 1335.4 1337.5 1339.6 897.3 911.2 928.1 930.3 969.0 5489.9 5639.7 5758.5 5768.4 5881.9 900.7 964.2 969.3 972.0 975.3 3771.9 3785.5 3861.5 3952.1 3975.3 9693.5 9931.4 9936.5 9936.7 10070.0 8156.0 9753.9 9865.3 9869.0 9945.6 7578.4 8014.3 8020.2 8033.2 8137.3 5475.9 5480.9 5585.5 5675.4 5770.8 9000.3 9109.7 9287.5 9319.3 9323.0 8794.2 9081.3 9263.6 9268.7 9377.6 7820.9 7907.9 7913.3 7938.8 7953.8 7840.5 7875.5 7893.2 7902.6 7912.3 7010.2 7183.1 7224.4 7227.4 7324.1 7137.8 7183.0 7192.6 7222.3 7268.5 6465.7 6541.0 6545.4 6550.1 6552.1 3954.2 3955.0 4752.6 4850.2 4888.2 4471.2 4639.0 4728.3 4799.7 4825.7 11:08:09 INFO - PROCESS | 1958 | 11:08:09 INFO - PROCESS | 1958 | [#4] prototype.html Cycles:180 Average:5888.68 Median:6670.67 stddev:1882.79 (28.2%) stddev-sans-first:1839.97 11:08:09 INFO - PROCESS | 1958 | Values: 255.0 315.9 325.7 325.9 330.6 5343.6 6669.0 6672.3 6684.7 6794.4 6421.2 6497.9 6645.6 6693.8 6724.9 5024.8 5025.1 5029.5 5044.2 5067.3 5041.1 5049.9 5051.8 5054.3 5066.0 4758.7 4826.7 4920.2 4994.7 5011.1 4967.0 4971.2 5001.4 5016.8 5019.3 4292.5 4300.0 4302.2 4302.4 4305.6 5235.9 6458.8 6471.3 6480.1 6494.7 5813.4 5842.4 5854.6 5864.2 5882.6 6866.5 7097.9 7134.6 7143.0 7150.2 4731.1 4756.0 4765.1 4772.6 4772.8 7543.7 7549.1 7553.0 7560.3 7575.7 4819.5 4838.6 4843.0 4851.2 4854.4 4922.6 4942.6 4942.7 4946.3 4949.1 5657.3 5747.3 5830.0 5831.8 5848.9 7147.6 7187.1 7202.6 7204.6 7210.5 1578.3 1593.9 1626.2 1694.6 1725.6 1050.8 1067.9 1075.3 1076.8 1088.6 5542.7 6632.9 6635.3 6680.6 6698.7 7406.9 7418.9 7421.2 7431.9 7439.4 6651.2 6676.3 6677.3 6679.6 6695.6 3419.4 3685.7 3816.2 3941.6 3979.3 7854.9 7902.7 7910.4 7922.2 7932.9 7780.2 7780.4 7780.9 7785.9 7795.9 7881.5 7895.7 7907.1 7908.1 7929.6 7197.7 7423.0 7425.2 7436.1 7511.0 6716.6 7339.7 7359.3 7367.3 7372.1 7854.4 7860.7 7868.5 7895.3 7903.9 4660.8 4716.3 4718.7 4720.2 4728.5 5851.4 7128.0 7458.8 7458.9 7463.9 5793.6 6792.9 7242.3 7244.7 7258.2 7238.3 7254.9 7258.1 7264.5 7276.5 7073.1 7083.6 7093.1 7094.9 7116.3 6876.8 6922.6 7206.5 7206.8 7261.6 6861.5 6915.9 6934.2 6937.6 6948.6 11:08:09 INFO - PROCESS | 1958 | 11:08:09 INFO - PROCESS | 1958 | [#5] yui.html Cycles:180 Average:17943.56 Median:35105.56 stddev:25773.77 (73.4%) stddev-sans-first:25813.55 11:08:09 INFO - PROCESS | 1958 | Values: 701.3 749.3 755.3 757.5 806.0 14838.3 14840.9 14965.6 14999.6 15098.6 12221.6 13676.8 13744.4 13753.2 13760.9 699.5 895.3 896.4 896.6 898.2 889.9 905.1 905.6 906.0 909.6 956.4 956.5 956.9 958.4 960.0 769.6 784.2 786.1 786.6 954.3 704.4 705.0 707.1 707.9 711.0 14498.7 14574.9 14577.6 14587.7 14616.9 10699.3 11865.5 12265.0 12497.3 12716.4 9826.0 9850.5 10155.6 10180.3 10301.8 3504.0 3513.2 3545.7 3578.5 3724.1 80944.0 82207.7 83020.2 83234.0 83374.6 9262.7 9299.6 9341.1 9355.6 9538.5 20475.2 20567.6 20572.6 20582.0 20592.8 7392.3 7519.2 7527.9 7629.3 7787.6 4069.7 4083.6 4137.4 4221.6 4237.7 3539.8 3596.2 3597.9 3600.5 3601.4 647.6 665.9 670.5 670.8 683.0 551.0 580.9 608.1 612.0 613.9 7686.4 7979.0 8047.0 8180.8 8296.5 507.8 580.9 591.3 600.9 604.3 3755.9 3758.1 3775.2 3908.7 3954.5 90923.1 92049.0 92145.0 92546.5 92707.0 55131.2 77242.0 78115.2 78946.6 80399.6 68839.7 69385.3 69471.0 69509.7 69511.7 7462.2 7597.0 7759.6 7763.3 7777.3 48409.8 49075.1 49628.0 49674.0 50655.5 65989.3 71305.0 71548.0 71552.6 72356.3 9560.0 9774.0 9778.8 10004.1 10067.5 10256.7 10486.1 10617.0 10618.9 10711.4 9421.9 9944.6 10133.2 10171.9 10367.4 9391.1 10040.1 10090.6 10235.4 10235.9 9822.3 9920.0 9927.3 10014.6 10120.8 8555.3 8707.5 8742.6 8874.4 8903.9 8611.1 8651.8 8670.9 8744.9 8795.5 11:08:09 INFO - PROCESS | 1958 | -------- Summary: end -------- 11:08:09 INFO - PROCESS | 1958 | 11:08:10 INFO - TEST-INFO | 1958: exit 0 11:08:10 INFO - TEST-OK | dromaeo_css | took 1114361ms 11:08:10 INFO - TEST-START | kraken 11:08:10 INFO - operating with platform_type : mac_ 11:08:10 INFO - Initialising browser for kraken test... 11:08:10 INFO - TEST-INFO | started process 1962 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmp0dOppK/profile http://localhost:49674/getInfo.html) 11:08:12 INFO - PROCESS | 1962 | __metrics Screen width/height:1600/1200 11:08:12 INFO - PROCESS | 1962 | colorDepth:24 11:08:12 INFO - PROCESS | 1962 | Browser inner width/height: 1024/689 11:08:12 INFO - PROCESS | 1962 | __metrics 11:08:12 INFO - PROCESS | 1962 | 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" 11:08:12 ERROR - PROCESS | 1962 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:08:13 INFO - TEST-INFO | 1962: exit 0 11:08:13 INFO - Browser initialized. 11:08:13 INFO - Running cycle 1/1 for kraken test... 11:08:13 INFO - TEST-INFO | started process 1964 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmp0dOppK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:08:25 INFO - PROCESS | 1964 | RSS: Main: 211435520 11:08:25 INFO - PROCESS | 1964 | 11:08:28 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49674/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49674/tests/kraken/driver.html?testName=audio-beat-detection) 11:08:29 INFO - PROCESS | 1964 | RSS: Main: 274427904 11:08:29 INFO - PROCESS | 1964 | 11:08:31 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49674/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49674/tests/kraken/driver.html?testName=audio-dft) 11:08:32 INFO - PROCESS | 1964 | RSS: Main: 289280000 11:08:32 INFO - PROCESS | 1964 | 11:08:35 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49674/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49674/tests/kraken/driver.html?testName=audio-fft) 11:08:36 INFO - PROCESS | 1964 | RSS: Main: 376000512 11:08:36 INFO - PROCESS | 1964 | 11:08:37 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49674/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49674/tests/kraken/driver.html?testName=audio-oscillator) 11:08:38 INFO - PROCESS | 1964 | RSS: Main: 280457216 11:08:38 INFO - PROCESS | 1964 | 11:08:40 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49674/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49674/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:08:41 INFO - PROCESS | 1964 | RSS: Main: 280805376 11:08:41 INFO - PROCESS | 1964 | 11:08:44 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49674/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49674/tests/kraken/driver.html?testName=imaging-darkroom) 11:08:44 INFO - PROCESS | 1964 | RSS: Main: 320835584 11:08:44 INFO - PROCESS | 1964 | 11:08:47 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49674/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49674/tests/kraken/driver.html?testName=imaging-desaturate) 11:08:48 INFO - PROCESS | 1964 | RSS: Main: 320282624 11:08:48 INFO - PROCESS | 1964 | 11:08:51 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49674/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49674/tests/kraken/driver.html?testName=json-parse-financial) 11:08:51 INFO - PROCESS | 1964 | RSS: Main: 319934464 11:08:51 INFO - PROCESS | 1964 | 11:08:53 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49674/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49674/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:08:53 INFO - PROCESS | 1964 | RSS: Main: 310677504 11:08:53 INFO - PROCESS | 1964 | 11:08:55 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49674/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49674/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:08:56 INFO - PROCESS | 1964 | RSS: Main: 318218240 11:08:56 INFO - PROCESS | 1964 | 11:08:58 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49674/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49674/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:08:59 INFO - PROCESS | 1964 | RSS: Main: 321634304 11:08:59 INFO - PROCESS | 1964 | 11:09:02 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49674/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49674/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:09:02 INFO - PROCESS | 1964 | RSS: Main: 323174400 11:09:02 INFO - PROCESS | 1964 | 11:09:05 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49674/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49674/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:09:06 INFO - PROCESS | 1964 | RSS: Main: 325373952 11:09:06 INFO - PROCESS | 1964 | 11:09:07 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49674/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49674/tests/kraken/driver.html?testName=ai-astar) 11:09:08 INFO - PROCESS | 1964 | RSS: Main: 387203072 11:09:08 INFO - PROCESS | 1964 | 11:09:08 INFO - PROCESS | 1964 | __start_tp_report 11:09:08 INFO - PROCESS | 1964 | _x_x_mozilla_page_load 11:09:08 INFO - PROCESS | 1964 | _x_x_mozilla_page_load_details 11:09:08 INFO - PROCESS | 1964 | |i|pagename|runs| 11:09:08 INFO - PROCESS | 1964 | |0;ai-astar;116;115;119;114;113;113;115;116;115;136 11:09:08 INFO - PROCESS | 1964 | |1;audio-beat-detection;160;127;141;168;121;141;134;122;170;118 11:09:08 INFO - PROCESS | 1964 | |2;audio-dft;189;187;184;180;185;187;185;182;186;185 11:09:08 INFO - PROCESS | 1964 | |3;audio-fft;67;75;72;159;56;57;55;65;133;56 11:09:08 INFO - PROCESS | 1964 | |4;audio-oscillator;91;91;142;76;79;81;131;79;78;124 11:09:08 INFO - PROCESS | 1964 | |5;imaging-gaussian-blur;87;85;88;85;87;85;88;84;87;85 11:09:08 INFO - PROCESS | 1964 | |6;imaging-darkroom;134;130;93;94;93;93;93;93;93;93 11:09:08 INFO - PROCESS | 1964 | |7;imaging-desaturate;84;124;141;140;85;84;83;83;86;86 11:09:08 INFO - PROCESS | 1964 | |8;json-parse-financial;61;62;62;62;63;64;62;82;60;57 11:09:08 INFO - PROCESS | 1964 | |9;json-stringify-tinderbox;64;66;73;80;74;72;66;67;68;70 11:09:08 INFO - PROCESS | 1964 | |10;stanford-crypto-aes;82;80;80;81;81;80;80;82;81;86 11:09:08 INFO - PROCESS | 1964 | |11;stanford-crypto-ccm;146;149;153;129;135;130;123;129;187;143 11:09:08 INFO - PROCESS | 1964 | |12;stanford-crypto-pbkdf2;168;163;166;160;166;167;165;166;164;165 11:09:08 INFO - PROCESS | 1964 | |13;stanford-crypto-sha256-iterative;67;68;70;67;68;67;69;69;66;68 11:09:08 INFO - PROCESS | 1964 | __end_tp_report 11:09:08 INFO - PROCESS | 1964 | __start_cc_report 11:09:08 INFO - PROCESS | 1964 | _x_x_mozilla_cycle_collect,1987 11:09:08 INFO - PROCESS | 1964 | __end_cc_report 11:09:08 INFO - PROCESS | 1964 | __startTimestamp1465236548041__endTimestamp 11:09:08 INFO - PROCESS | 1964 | 11:09:08 INFO - PROCESS | 1964 | ------- Summary: start ------- 11:09:08 INFO - PROCESS | 1964 | Number of tests: 14 11:09:08 INFO - PROCESS | 1964 | 11:09:08 INFO - PROCESS | 1964 | [#0] ai-astar Cycles:10 Average:117.20 Median:115.50 stddev:6.83 (5.9%) stddev-sans-first:7.23 11:09:08 INFO - PROCESS | 1964 | Values: 116.0 115.0 119.0 114.0 113.0 113.0 115.0 116.0 115.0 136.0 11:09:08 INFO - PROCESS | 1964 | 11:09:08 INFO - PROCESS | 1964 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:141.00 stddev:19.61 (13.9%) stddev-sans-first:19.44 11:09:08 INFO - PROCESS | 1964 | Values: 160.0 127.0 141.0 168.0 121.0 141.0 134.0 122.0 170.0 118.0 11:09:08 INFO - PROCESS | 1964 | 11:09:08 INFO - PROCESS | 1964 | [#2] audio-dft Cycles:10 Average:185.00 Median:185.50 stddev:2.58 (1.4%) stddev-sans-first:2.30 11:09:08 INFO - PROCESS | 1964 | Values: 189.0 187.0 184.0 180.0 185.0 187.0 185.0 182.0 186.0 185.0 11:09:08 INFO - PROCESS | 1964 | 11:09:08 INFO - PROCESS | 1964 | [#3] audio-fft Cycles:10 Average:79.50 Median:61.00 stddev:36.27 (59.5%) stddev-sans-first:38.18 11:09:08 INFO - PROCESS | 1964 | Values: 67.0 75.0 72.0 159.0 56.0 57.0 55.0 65.0 133.0 56.0 11:09:08 INFO - PROCESS | 1964 | 11:09:08 INFO - PROCESS | 1964 | [#4] audio-oscillator Cycles:10 Average:97.20 Median:79.00 stddev:25.14 (31.8%) stddev-sans-first:26.56 11:09:08 INFO - PROCESS | 1964 | Values: 91.0 91.0 142.0 76.0 79.0 81.0 131.0 79.0 78.0 124.0 11:09:08 INFO - PROCESS | 1964 | 11:09:08 INFO - PROCESS | 1964 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.00 stddev:1.45 (1.7%) stddev-sans-first:1.50 11:09:08 INFO - PROCESS | 1964 | Values: 87.0 85.0 88.0 85.0 87.0 85.0 88.0 84.0 87.0 85.0 11:09:08 INFO - PROCESS | 1964 | 11:09:08 INFO - PROCESS | 1964 | [#6] imaging-darkroom Cycles:10 Average:100.90 Median:93.00 stddev:16.42 (17.7%) stddev-sans-first:12.30 11:09:08 INFO - PROCESS | 1964 | Values: 134.0 130.0 93.0 94.0 93.0 93.0 93.0 93.0 93.0 93.0 11:09:08 INFO - PROCESS | 1964 | 11:09:08 INFO - PROCESS | 1964 | [#7] imaging-desaturate Cycles:10 Average:99.60 Median:84.00 stddev:24.86 (29.6%) stddev-sans-first:25.72 11:09:08 INFO - PROCESS | 1964 | Values: 84.0 124.0 141.0 140.0 85.0 84.0 83.0 83.0 86.0 86.0 11:09:08 INFO - PROCESS | 1964 | 11:09:08 INFO - PROCESS | 1964 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:62.00 stddev:6.77 (10.9%) stddev-sans-first:7.12 11:09:08 INFO - PROCESS | 1964 | Values: 61.0 62.0 62.0 62.0 63.0 64.0 62.0 82.0 60.0 57.0 11:09:08 INFO - PROCESS | 1964 | 11:09:08 INFO - PROCESS | 1964 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:71.00 stddev:4.83 (6.8%) stddev-sans-first:4.61 11:09:08 INFO - PROCESS | 1964 | Values: 64.0 66.0 73.0 80.0 74.0 72.0 66.0 67.0 68.0 70.0 11:09:08 INFO - PROCESS | 1964 | 11:09:08 INFO - PROCESS | 1964 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:81.00 stddev:1.83 (2.3%) stddev-sans-first:1.92 11:09:08 INFO - PROCESS | 1964 | Values: 82.0 80.0 80.0 81.0 81.0 80.0 80.0 82.0 81.0 86.0 11:09:08 INFO - PROCESS | 1964 | 11:09:08 INFO - PROCESS | 1964 | [#11] stanford-crypto-ccm Cycles:10 Average:142.40 Median:144.50 stddev:18.57 (12.8%) stddev-sans-first:19.65 11:09:08 INFO - PROCESS | 1964 | Values: 146.0 149.0 153.0 129.0 135.0 130.0 123.0 129.0 187.0 143.0 11:09:08 INFO - PROCESS | 1964 | 11:09:08 INFO - PROCESS | 1964 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:166.00 stddev:2.26 (1.4%) stddev-sans-first:2.12 11:09:08 INFO - PROCESS | 1964 | Values: 168.0 163.0 166.0 160.0 166.0 167.0 165.0 166.0 164.0 165.0 11:09:08 INFO - PROCESS | 1964 | 11:09:08 INFO - PROCESS | 1964 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:1.20 (1.8%) stddev-sans-first:1.22 11:09:08 INFO - PROCESS | 1964 | Values: 67.0 68.0 70.0 67.0 68.0 67.0 69.0 69.0 66.0 68.0 11:09:08 INFO - PROCESS | 1964 | -------- Summary: end -------- 11:09:08 INFO - PROCESS | 1964 | 11:09:08 INFO - TEST-INFO | 1964: exit 0 11:09:08 INFO - TEST-OK | kraken | took 58363ms 11:09:08 INFO - SUITE-END | took 1172s 11:09:09 INFO - Completed test suite (00:19:33) 11:09:09 INFO - javascript benchmark 11:09:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3347.3138006708627, 3433.1588760753634, 3435.2614187949584, 3450.84471198796, 3495.161161321282, 5660.29246344207, 5666.235058267132, 5667.093265077583, 5677.120046392805, 5682.374938756739, 5169.630048037273, 5289.259503669489, 5658.066419040863, 5710.14347847822, 5712.086066229404, 5152.639315247934, 5162.638615296931, 5222.817201397952, 5265.3155089838265, 5307.840764777063, 4382.364557139213, 5138.743062846861, 5267.183586544073, 5272.919051594414, 5273.630845840793, 5166.741662916858, 5224.425313215544, 5247.86880327991, 5248.186531087668, 5261.5264626183825, 5226.529612334889, 5271.446498117681, 5284.207368894658, 5284.6564973277, 5317.388500322435, 5111.565516931066, 5143.305653736773, 5166.793328266865, 5220.817271395521, 5244.475552444763, 5505.421930697259, 5527.060399732055, 5549.417311182308, 5549.805756798533, 5565.0817615093465, 6174.691265436755, 6202.472789812871, 6226.159468471744, 6235.0, 6235.968820155916, 6057.922415208467, 6179.474744646711, 6238.688065596702, 6270.247570291549, 6291.056341548759, 5769.509591684669, 5777.711114444261, 5779.133130030529, 5780.219670344533, 5798.739056742456, 6262.3111457739615, 6290.0, 6291.496680265568, 6296.937030629727, 6306.274778400542, 6031.185789918392, 6089.086637004396, 6115.331611965994, 6156.846078847975, 6162.352952939967, 6256.718447669866, 6257.687115644199, 6272.498200143978, 6272.780452684134, 6280.057991301342, 6180.0, 6217.6891155442045, 6222.751089956441, 6226.719797609118, 6237.064440333987, 6094.908576371358, 6164.6917654117115, 6164.845878853064, 6167.506599472032, 6234.189555357865, 4841.806327746851, 4895.996320754308, 4902.411710594752, 4983.476734942851, 4991.550760431506, 4409.096135292258, 4420.403245561873, 4420.535843736361, 4429.490608580055, 4469.776511174428, 5322.680639161663, 5487.75305111271, 5681.715914204273, 5708.086706127, 5741.339745929188, 6033.3061697905305, 6158.076288556752, 6220.222472190976, 6232.906506402408, 6251.593646412968, 5626.774929002876, 5633.436656334416, 5643.858903527444, 5644.463775941279, 5661.688607126647, 5374.0, 5385.3268341457315, 5386.467049753029, 5393.056215162331, 5437.8368648940595, 6223.688815559204, 6269.153664255267, 6323.051542268697, 6329.810105696836, 6335.176427064452, 6207.875842483035, 6252.343503932022, 6253.0, 6272.466840318624, 6278.497720182466, 6296.307406185408, 6313.715882785194, 6314.273858506147, 6336.144620476177, 6348.746050158126, 5990.610610310316, 6097.695115244309, 6129.969350153221, 6140.0, 6140.293866205265, 6133.26400831903, 6147.231596050494, 6208.689565521796, 6210.0, 6228.532860035425, 6118.694065296806, 6136.140940268277, 6157.384261573807, 6158.784442544489, 6164.6917654118015, 6076.361981991828, 6147.200863887666, 6150.7539698411565, 6152.354002829638, 6160.353162917829, 4998.0, 5799.014167591436, 5893.0, 5956.106584012433, 5983.222181116601, 5982.162497250475, 6075.058365953284, 6099.420555047263, 6105.262111899988, 6122.0, 5803.0, 5867.471927526543, 5913.2608423947, 5915.378885217162, 5928.762849486145], "value": 5676.996222960953, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.8738559053032, 519.4831143012702, 536.975836087376, 561.1610642090075, 578.0289114287995, 30775.76854430286, 38279.42580861286, 39401.21197576047, 39431.605683943155, 39488.80255598718, 41533.792331038305, 42029.949251268736, 42085.789571052104, 42097.0, 42209.788951055205, 8387.706430274942, 9453.10195531425, 9660.565274562627, 9666.27502937279, 9671.645969564232, 8523.914760852405, 8638.481691098523, 8641.092685268051, 11524.769504609903, 11828.349440780798, 11479.311241325506, 11564.768704625902, 11566.132540059487, 11566.884331156707, 11571.710707232345, 11526.19316647835, 11556.711082222928, 11557.711057223552, 11562.0, 11569.016633586156, 8505.617247223829, 8717.041125476193, 8718.0, 8726.738197854074, 10628.893711062869, 38302.42546361779, 38855.41716874249, 38943.805280973414, 39044.0, 39064.414033789515, 3143.654198038214, 3281.5898012748407, 3289.0626171540994, 3501.719862411001, 3720.776753394805, 26019.0, 27378.863105684344, 27867.0, 27911.883524658988, 28101.0, 2677.678678558566, 2708.2010806812036, 2718.4834881372476, 2723.2340518678075, 2850.828950262991, 381347.0, 381749.09125454474, 382181.08909455553, 382863.0, 383574.0, 59528.0, 59968.40031599628, 60080.699596502614, 60123.39876601178, 60221.09668354891, 63405.04892426617, 63560.0, 63712.362876370644, 63735.362646372945, 63743.681281594225, 15019.774703379459, 17267.74098388525, 17449.912750436168, 17456.738148927776, 17464.039477828588, 11541.884581154081, 11589.826152607717, 11603.825942610867, 11617.244879082833, 13410.0, 15408.998415103206, 15644.296006679499, 15654.0, 15661.765073523906, 15732.842671573366, 6951.304869513109, 7101.538400004087, 7134.286571342928, 7145.571265724073, 7186.784396468114, 986.5757724178673, 998.7453199434098, 1030.8299130643509, 1099.7140743406771, 1114.6098865397207, 29218.977335793144, 29724.0, 29814.701852981194, 30066.54900176499, 30273.848630756704, 1035.3632516002604, 1042.9883013476915, 1108.4900945564968, 1122.320995797547, 1182.2079206931376, 15079.170645614597, 15106.0, 15106.244687765568, 15488.922555387377, 15547.689046219239, 576212.0, 581015.0, 581622.0, 581969.0, 583994.0, 59717.0, 59858.102128468105, 60000.39999600036, 60019.699901500215, 60028.0, 1457.7157454296523, 1542.2982542942934, 1550.99224503879, 1556.968860622804, 1559.7582374731935, 24545.63181552314, 28802.711972880843, 29581.26046848846, 29741.0, 30150.69849301479, 48026.0, 48341.758291208316, 48508.787280318276, 48514.75742621264, 48572.0, 437947.0, 439720.0, 440833.0, 441958.0, 442432.0, 32777.83611081929, 39052.82841514759, 39353.80323098366, 40079.39880901847, 40873.795631021654, 42414.0, 42515.0, 42792.572074280106, 43594.12811743811, 43908.560914390444, 34892.30215395729, 35494.112647184025, 36833.63166368302, 36954.815225923696, 37262.06844828901, 32341.838290808395, 34093.8067167648, 36435.27129457449, 36520.26959460743, 37212.44181337336, 36754.63245367619, 36761.448578270814, 36924.630753692116, 37244.6275537252, 37314.06714832151, 25807.741922581285, 30189.698103018687, 31502.36995260128, 32741.83629081839, 38577.0, 30003.849980749957, 30242.0, 31036.534451982785, 31247.0, 31905.0], "value": 19707.613340809447, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.5923837305136, 401.8633664554051, 402.1675132475775, 404.1230529750441, 420.30229818501294, 19255.518612034706, 19667.5083122922, 20330.796692033113, 20331.39005829824, 20358.185672573098, 19596.608067838635, 20017.899910500462, 20730.0, 21461.034253458594, 21472.570548589018, 4877.82927597535, 5917.497012753921, 6124.356942521015, 6135.6625385603775, 6202.162708034425, 6027.789027384042, 6112.602680825732, 6116.143739876398, 6122.510199184077, 6133.2026836511195, 5816.476517113459, 6060.2424696912885, 6079.05774604937, 6120.1125836753645, 6128.417800308964, 5978.342382337939, 6016.879662406749, 6030.185924900126, 6035.245594300713, 6042.093685947099, 5173.689578625294, 5222.634415590872, 5306.965141797351, 5314.867128321785, 5341.0, 26314.60528092061, 26471.0, 26677.733222667717, 26869.462610747676, 26912.73087269122, 1975.6345076160956, 2084.4267826347723, 2104.989475052615, 2184.17001539414, 2754.228815931542, 9552.665656701982, 10273.537690803932, 12052.578159764455, 14715.969882108257, 15261.313240904075, 1754.9122543872754, 1799.379214171109, 1820.9635807283912, 1844.9538761530935, 1845.0866820923595, 107258.92741072568, 111257.0, 117830.41084794607, 123245.38377308146, 125241.22346541873, 11735.4132293385, 11775.116866234895, 11811.88188118125, 11891.524339026515, 11933.940330298294, 15194.468193613171, 15207.695846083016, 15209.315580798891, 15230.0, 15256.389744410099, 8484.490930544187, 8883.37816352862, 8900.198982091513, 8984.056674049261, 9072.228860546795, 8897.11028897118, 8939.284857211407, 8963.282937364995, 9028.458292502472, 9037.819243615222, 4621.838235661736, 4761.119357853629, 4761.809527618856, 4783.330333753277, 4825.5174482551465, 1961.8920959347372, 2018.162462578043, 2039.184326269504, 2062.658882168749, 2064.5870825834895, 785.7407055671615, 866.6013633728429, 990.2127808392345, 1021.7190272675074, 1023.048564834696, 19681.90159049224, 21352.893235533724, 22372.32883013512, 22405.991730372174, 22413.327600171695, 936.1060187520901, 959.960084819758, 974.8878878928872, 989.5794287427815, 999.1415966302816, 10731.195160363004, 11032.227744058, 11046.447677616086, 11068.335899846035, 11077.0, 512882.0, 546550.0, 626172.0, 638473.0, 642846.0, 136111.8749656248, 136214.0, 136713.94929075873, 138342.30828845792, 143167.85248221285, 1066.4134725900724, 1072.463768115942, 1086.527360598158, 1087.8205096159204, 1088.9346639201515, 18231.345364726887, 18346.990915499777, 18578.628427431646, 18687.0, 18815.435536933914, 54186.91626167376, 55163.724181378835, 55220.447795523134, 55588.0, 56227.43772562222, 79703.0, 79845.0, 80588.0, 83187.58406208172, 84784.57607712169, 8182.590870456573, 8209.548474833702, 8214.067187010227, 8320.958395207985, 8331.875021874555, 9319.0, 12051.096167787626, 12222.449989750661, 12307.0, 12654.0, 10643.787124257626, 10754.731131721457, 10976.890231097586, 10994.34033957965, 11064.612738554433, 10452.738681533023, 10489.318194317346, 10854.18593605467, 10863.837042444527, 11110.50002749862, 6165.938340616536, 11179.888201117885, 11436.714082148013, 11581.826272606086, 11664.533418663159, 8137.308328792119, 8171.632276547451, 8218.671253149807, 8344.749657510285, 8531.0, 8175.018997720312, 10030.49847507607, 10966.945165274123, 11259.549618015188, 11316.660500185008], "value": 9749.172430891813, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1423.7721964485672, 1553.9145347005913, 1571.7249481340766, 1807.4848668129578, 1955.3254127326068, 7737.303642672158, 8325.542095184763, 8497.0, 8506.447080939735, 8512.106228845974, 8236.752897413087, 8474.872876906851, 8494.745157645266, 8504.107068757785, 8506.787330316729, 2460.470998735276, 2488.4525404411, 2491.252624212738, 2491.352248415407, 2494.4886298308893, 2413.2760171948435, 2473.703155621328, 2479.6776419065495, 2481.1192026830486, 2482.391814005562, 2443.6701045358914, 2476.2447453526665, 2487.2289590226997, 2487.987560062197, 2489.8879550420183, 2434.732179460258, 2460.2127319257843, 2472.3682369636044, 2480.950380992388, 2483.801295896324, 1816.8546516278798, 1957.784643689193, 2426.623873299641, 2441.853488790678, 2444.8655323957173, 7180.210176880539, 7249.637518124073, 7548.396128309777, 7739.806504837367, 8590.87113693295, 4864.416270047617, 4962.379702537183, 4970.453250142481, 5212.973935130338, 5288.894222115536, 3388.678075582816, 3429.416999110157, 3434.6221915589267, 3452.844621991991, 3481.547398838135, 959.0649117110831, 1016.466355163545, 1042.7393151712072, 1047.33494231163, 1068.8770791358938, 10876.510557024953, 10927.836082458769, 10933.016028557468, 10952.726181845437, 10954.726131846768, 4675.929861052087, 4716.528347165256, 4743.501932297059, 4749.2876068589985, 4798.632389768899, 9517.666881659246, 9606.759831004281, 9617.51912404377, 9620.567074481665, 9621.08599683029, 5253.894922101613, 5259.526642602184, 5284.9735751320995, 5286.286351342596, 5296.92054619181, 4014.0, 4714.4106986626675, 4723.811047558059, 4732.361131247238, 4736.360591320196, 3382.9512851016266, 3852.3065848147603, 3855.1133239355104, 3944.723869329177, 3973.980130099389, 1137.3801278303345, 1329.4482789642384, 1335.3990704183157, 1337.4855272088412, 1339.6249050265942, 897.3045889435609, 911.2072496927715, 928.1043792740038, 930.2883294279882, 968.9563969621383, 5489.917651235235, 5639.689817060101, 5758.481736643722, 5768.423157684282, 5881.911771323434, 900.6622516556291, 964.1659964130977, 969.336004836675, 971.9611215551299, 975.2588033094898, 3771.905702357463, 3785.545734511876, 3861.4593956846065, 3952.071263253133, 3975.3043217436834, 9693.515324233902, 9931.404115753077, 9936.50317484123, 9936.652217172365, 10070.0, 8156.0, 9753.902460975441, 9865.260105492116, 9868.963758805223, 9945.552450139761, 7578.4316176287, 8014.318782903402, 8020.157883422156, 8033.236842499953, 8137.267645911897, 5475.890482190254, 5480.917786233289, 5585.469380408855, 5675.432456754292, 5770.826875193751, 9000.324975626723, 9109.681161159328, 9287.48918809472, 9319.347645664739, 9322.95338523303, 8794.164554367326, 9081.318901082577, 9263.629454821732, 9268.675596354095, 9377.578008989476, 7820.921790782019, 7907.881381779162, 7913.327367173854, 7938.841223175619, 7953.8409231816195, 7840.490368126054, 7875.527468351688, 7893.17121702213, 7902.565358905315, 7912.28789408956, 7010.228874823867, 7183.138023437221, 7224.385927196247, 7227.38567221771, 7324.08448943882, 7137.750178743719, 7183.0, 7192.568445893263, 7222.313880181375, 7268.527545709512, 6465.741370345134, 6541.0, 6545.443637290884, 6550.050242714684, 6552.148220731275, 3954.1696243789124, 3954.980225098856, 4752.596029337406, 4850.1997170466475, 4888.193448081169, 4471.217536930959, 4638.953610463852, 4728.290756386569, 4799.66402351832, 4825.734584597741], "value": 4462.32914613394, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [254.96685430893982, 315.94628913084773, 325.6743256743257, 325.9445894197986, 330.57686161713, 5343.572514198864, 6669.032990216422, 6672.299408562104, 6684.699188536521, 6794.3545363190415, 6421.197350331208, 6497.870042599146, 6645.6344901030425, 6693.765718199864, 6724.865502689943, 5024.849254522352, 5025.14572522672, 5029.547340739333, 5044.167712327461, 5067.265246539258, 5041.067402530525, 5049.949500505003, 5051.823186188484, 5054.317667114948, 5066.012127635113, 4758.666893317469, 4826.734529600888, 4920.23736320869, 4994.675346102492, 5011.098002359574, 4967.0, 4971.204607262839, 5001.374828146481, 5016.774245158977, 5019.347484826967, 4292.484901811803, 4299.978500107511, 4302.161078589676, 4302.440682711227, 4305.590968857953, 5235.895282094337, 6458.838529036765, 6471.288158302583, 6480.125183100316, 6494.740210391578, 5813.4477224664015, 5842.357340692521, 5854.590178687494, 5864.208331875185, 5882.617629854046, 6866.519343645947, 7097.929020709778, 7134.607596582186, 7143.035690181811, 7150.1777295611155, 4731.124741922721, 4755.976220118911, 4765.11845308619, 4772.642051846091, 4772.80908763649, 7543.69825206991, 7549.0558682114015, 7552.962235188844, 7560.281773231534, 7575.659095340723, 4819.49395313494, 4838.612910967114, 4843.0313937211995, 4851.199552073868, 4854.441739200037, 4922.58158056562, 4942.579880710108, 4942.728149951715, 4946.258061290835, 4949.1091603510995, 5657.263854747228, 5747.25285712863, 5830.0, 5831.79588714393, 5848.883022339614, 7147.571145731273, 7187.065681461481, 7202.603856787927, 7204.639768011683, 7210.495265331481, 1578.273993962767, 1593.8963967342086, 1626.1543997121667, 1694.5932976085896, 1725.6462425203053, 1050.779336339362, 1067.893210678926, 1075.3171735947728, 1076.8061748885275, 1088.6244245735209, 5542.695151766611, 6632.900506492407, 6635.336466353326, 6680.5991640501725, 6698.665066746643, 7406.8888966665545, 7418.888716669254, 7421.183669796318, 7431.925680743123, 7439.404847612178, 6651.16860392451, 6676.298988606127, 6677.265500794909, 6679.599224046573, 6695.631740254333, 3419.4357930941114, 3685.723570732152, 3816.179521402856, 3941.6452519273403, 3979.303621866162, 7854.921450785419, 7902.723404680809, 7910.406719496061, 7922.207779222032, 7932.881006784903, 7780.18308077655, 7780.416468764865, 7780.922190778133, 7785.883211751829, 7795.883061754078, 7881.502514522337, 7895.749711332242, 7907.0906845712325, 7908.051033875741, 7929.563873986871, 7197.748078817216, 7422.96288518554, 7425.183229844821, 7436.144843343085, 7511.0, 6716.5634233774645, 7339.669714862737, 7359.264073592598, 7367.336939675455, 7372.115346158495, 7854.41091918097, 7860.724874629361, 7868.488548244346, 7895.328897043815, 7903.881441778493, 4660.790264438177, 4716.29255611661, 4718.740469274223, 4720.221163507983, 4728.534154412143, 5851.356350801493, 7128.037714908421, 7458.813529661802, 7458.888116678146, 7463.925360746323, 5793.594448388572, 6792.864142717217, 7242.275772422716, 7244.746433875, 7258.165310989092, 7238.312360325971, 7254.854902901807, 7258.0927384076995, 7264.491485595958, 7276.490645654965, 7073.115860517435, 7083.645817709197, 7093.077899872984, 7094.858102838018, 7116.252793456612, 6876.828079297858, 6922.58464492132, 7206.531575447579, 7206.819829504095, 7261.636918154177, 6861.4853885957755, 6915.853286754414, 6934.237233904166, 6937.618430986344, 6948.6178260196175], "value": 5246.164550146087, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.344243132671, 749.3181205103357, 755.3428517190042, 757.4773406349622, 805.9884844520128, 14838.258087095643, 14840.925795371022, 14965.551033468986, 14999.625009374771, 15098.622534436645, 12221.572244971429, 13676.794848077261, 13744.381502832372, 13753.174809511436, 13760.862391376108, 699.4753934549088, 895.3463971300954, 896.4397251717677, 896.6278994217424, 898.2320116300534, 889.8932128144631, 905.0949050949051, 905.5743800413828, 906.0486489186328, 909.5906841921129, 956.3926906414442, 956.5408603870146, 956.9186619137382, 958.4345236310576, 960.0, 769.5844244108175, 784.2079499705281, 786.080286065305, 786.5516655506335, 954.3033585482567, 704.387183150657, 704.989425158623, 707.0949185043153, 707.9362857342812, 711.0365454808712, 14498.71002579953, 14574.854251457456, 14577.635559111, 14587.70824583513, 14616.926915365462, 10699.304545204537, 11865.466054027589, 12265.0187984961, 12497.25016499013, 12716.427760750696, 9825.950870245675, 9850.458224797634, 10155.593776248941, 10180.33827801198, 10301.793964120676, 3504.0, 3513.2446523997464, 3545.663161999606, 3578.4632305154173, 3724.0689827543115, 80944.0, 82207.65650232715, 83020.16979830185, 83234.0, 83374.58312708397, 9262.72211833646, 9299.628014879465, 9341.065893410605, 9355.578998945064, 9538.52307384628, 20475.180992760124, 20567.588648226953, 20572.588548228952, 20582.0, 20592.794072059387, 7392.260773922672, 7519.248075192437, 7527.924720752722, 7629.2752188542, 7787.649555770004, 4069.694772892045, 4083.5916408358926, 4137.40007698888, 4221.6200541951375, 4237.724547904377, 3539.7699149555197, 3596.1549035976523, 3597.8740744074357, 3600.513930619385, 3601.4237721964882, 647.6470323673609, 665.9467242620676, 670.5205777868749, 670.8222321084917, 683.0198664915862, 550.9752061157258, 580.9186713860063, 608.0726891490513, 611.9602225855305, 613.9017757158833, 7686.423518236043, 7979.0, 8046.994125734283, 8180.83638327242, 8296.502209867427, 507.7994192294023, 580.9390014048548, 591.3465620489297, 600.8768202518473, 604.2869414091414, 3755.9436608450897, 3758.1168425420005, 3775.188334508039, 3908.726389152732, 3954.4661470701662, 90923.09076909146, 92049.0, 92145.0, 92546.53726731324, 92707.0, 55131.17303240455, 77242.0, 78115.2188478108, 78946.60526697445, 80399.59800200962, 68839.65580172167, 69385.30614693889, 69470.95793563101, 69509.65245173742, 69511.65244173747, 7462.202323016054, 7596.962015189889, 7759.573223472763, 7763.340116090196, 7777.261160189773, 48409.7897552564, 49075.11098668183, 49628.0, 49674.0, 50655.493445065076, 65989.34010659832, 71305.0, 71548.0, 71552.5689486197, 72356.27643723495, 9559.952200239233, 9774.0, 9778.75553111178, 10004.149647280063, 10067.546960386653, 10256.692299231036, 10486.108680762221, 10617.044465998097, 10618.946905265424, 10711.410872402093, 9421.85867212006, 9944.602215911282, 10133.240006999356, 10171.949140254252, 10367.429791361548, 9391.107844754737, 10040.146587539848, 10090.646827361006, 10235.385876847413, 10235.897641023792, 9822.312438129547, 9919.950400247953, 9927.255455840696, 10014.599416023277, 10120.797584048425, 8555.275254514583, 8707.521086340312, 8742.562871856255, 8874.378793484653, 8903.910960890309, 8611.13888611134, 8651.783705407415, 8670.91329086701, 8744.912550874664, 8795.516246606498], "value": 6688.762715633364, "unit": "score"}], "name": "dromaeo_css", "value": 7448.771682225429, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 119.0, 114.0, 113.0, 113.0, 115.0, 116.0, 115.0, 136.0], "value": 117.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 127.0, 141.0, 168.0, 121.0, 141.0, 134.0, 122.0, 170.0, 118.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 187.0, 184.0, 180.0, 185.0, 187.0, 185.0, 182.0, 186.0, 185.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 75.0, 72.0, 159.0, 56.0, 57.0, 55.0, 65.0, 133.0, 56.0], "value": 79.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 142.0, 76.0, 79.0, 81.0, 131.0, 79.0, 78.0, 124.0], "value": 97.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 85.0, 87.0, 85.0, 88.0, 84.0, 87.0, 85.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 130.0, 93.0, 94.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0], "value": 100.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 124.0, 141.0, 140.0, 85.0, 84.0, 83.0, 83.0, 86.0, 86.0], "value": 99.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 62.0, 63.0, 64.0, 62.0, 82.0, 60.0, 57.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 73.0, 80.0, 74.0, 72.0, 66.0, 67.0, 68.0, 70.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 80.0, 81.0, 81.0, 80.0, 80.0, 82.0, 81.0, 86.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 149.0, 153.0, 129.0, 135.0, 130.0, 123.0, 129.0, 187.0, 143.0], "value": 142.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 163.0, 166.0, 160.0, 166.0, 167.0, 165.0, 166.0, 164.0, 165.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 70.0, 67.0, 68.0, 67.0, 69.0, 69.0, 66.0, 68.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1495.8000000000002, "alertThreshold": 2.0}]} 11:09:09 INFO - Return code: 0 11:09:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:09:09 ERROR - # TBPL SUCCESS # 11:09:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:09:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:09:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:09:09 INFO - 2016-06-06 11:09:09.339 screenresolution[1968:13992] starting screenresolution argv=screenresolution get 11:09:09 INFO - 2016-06-06 11:09:09.341 screenresolution[1968:13992] Display 0: 1600x1200x32@60 11:09:09 INFO - 2016-06-06 11:09:09.347 screenresolution[1969:13995] starting screenresolution argv=screenresolution list 11:09:09 INFO - Available Modes on Display 0 11:09:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:09:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:09:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:09:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:09:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:09:09 INFO - Intel Iris: 11:09:09 INFO - Chipset Model: Intel Iris 11:09:09 INFO - Type: GPU 11:09:09 INFO - Bus: Built-In 11:09:09 INFO - VRAM (Dynamic, Max): 1536 MB 11:09:09 INFO - Vendor: Intel (0x8086) 11:09:09 INFO - Device ID: 0x0a2e 11:09:09 INFO - Revision ID: 0x0009 11:09:09 INFO - Displays: 11:09:09 INFO - Display: 11:09:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:09:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:09:09 INFO - Display Serial Number: 1600x1200 60 11:09:09 INFO - Main Display: Yes 11:09:09 INFO - Mirror: Off 11:09:09 INFO - Online: Yes 11:09:09 INFO - Rotation: Supported 11:09:09 INFO - Return code: 0 11:09:09 INFO - Running post-action listener: _resource_record_post_action 11:09:09 INFO - Running post-run listener: _resource_record_post_run 11:09:10 INFO - Total resource usage - Wall time: 1206s; CPU: 52.0%; Read bytes: 1556480; Write bytes: 653926400; Read time: 15; Write time: 1810 11:09:10 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 353124864; Write bytes: 348018688; Read time: 27309; Write time: 644 11:09:10 INFO - run-tests - Wall time: 1175s; CPU: 52.0%; Read bytes: 1556480; Write bytes: 296159232; Read time: 15; Write time: 1133 11:09:10 INFO - Running post-run listener: _upload_blobber_files 11:09:10 INFO - Blob upload gear active. 11:09:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:09:10 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 11:09:10 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'] 11:09:10 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 11:09:11 INFO - (blobuploader) - INFO - Open directory for files ... 11:09:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:09:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:09:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:09:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:09:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:09:11 INFO - (blobuploader) - INFO - Done attempting. 11:09:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:09:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:09:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:09:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:09:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:09:13 INFO - (blobuploader) - INFO - Done attempting. 11:09:13 INFO - (blobuploader) - INFO - Iteration through files over. 11:09:13 INFO - Return code: 0 11:09:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:09:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:09:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d52f29d209014ae353849388ce90d42ad8fbb28abee91df79991d891e72fe09fd8eda8bb5c8b2ee435bca0d727cbf1b035e9865594018ad5cc1be19bd4e742a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ec9ff1b6745a4ec0e6c8228a85b68eeeb8195483d57b4c436da3a07661142cc129b497bd98a2b357d4362f897320964a62afa225ae64bcd7abc2004eb512b8b"} 11:09:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:09:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:09:13 INFO - Contents: 11:09:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d52f29d209014ae353849388ce90d42ad8fbb28abee91df79991d891e72fe09fd8eda8bb5c8b2ee435bca0d727cbf1b035e9865594018ad5cc1be19bd4e742a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ec9ff1b6745a4ec0e6c8228a85b68eeeb8195483d57b4c436da3a07661142cc129b497bd98a2b357d4362f897320964a62afa225ae64bcd7abc2004eb512b8b"} 11:09:13 INFO - Running post-run listener: copy_logs_to_upload_dir 11:09:13 INFO - Copying logs to upload dir... 11:09:13 INFO - mkdir: /builds/slave/test/build/upload/logs 11:09:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.308855 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-06-06 11:09:13.155947) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 11:09:13.160139) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d52f29d209014ae353849388ce90d42ad8fbb28abee91df79991d891e72fe09fd8eda8bb5c8b2ee435bca0d727cbf1b035e9865594018ad5cc1be19bd4e742a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ec9ff1b6745a4ec0e6c8228a85b68eeeb8195483d57b4c436da3a07661142cc129b497bd98a2b357d4362f897320964a62afa225ae64bcd7abc2004eb512b8b"} build_url:https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011096 build_url: 'https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d52f29d209014ae353849388ce90d42ad8fbb28abee91df79991d891e72fe09fd8eda8bb5c8b2ee435bca0d727cbf1b035e9865594018ad5cc1be19bd4e742a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ec9ff1b6745a4ec0e6c8228a85b68eeeb8195483d57b4c436da3a07661142cc129b497bd98a2b357d4362f897320964a62afa225ae64bcd7abc2004eb512b8b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 11:09:13.193732) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 11:09:13.194217) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IYwNXDZQ6Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.UHxxHulN1C/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005395 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 11:09:13.243381) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 11:09:13.243709) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 11:09:13.244061) ========= ========= Total master_lag: 0.19 =========