builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0154 starttime: 1468751824.77 results: success (0) buildid: 20160717013154 builduid: b29bf0c67b4642cdbf4ed5e6aa124749 revision: 92ec0fc8583e2a8bf38f0d8e98a3b78cda4eebaa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 03:37:04.765947) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 03:37:04.766932) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 03:37:04.767392) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 03:37:04.792381) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:37:04.793090) ========= 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.3gPCeXIk5f/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.GPbzJ7ZlO4/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-07-17 03:37:04-- 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% 29.6M=0s 2016-07-17 03:37:05 (29.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.341827 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:37:05.162452) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:37:05.163226) ========= 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.3gPCeXIk5f/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.GPbzJ7ZlO4/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.028823 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:37:05.217180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-17 03:37:05.217959) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 92ec0fc8583e2a8bf38f0d8e98a3b78cda4eebaa --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-beta --rev 92ec0fc8583e2a8bf38f0d8e98a3b78cda4eebaa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3gPCeXIk5f/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.GPbzJ7ZlO4/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-07-17 03:37:05,296 truncating revision to first 12 chars 2016-07-17 03:37:05,296 Setting DEBUG logging. 2016-07-17 03:37:05,296 attempt 1/10 2016-07-17 03:37:05,296 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/92ec0fc8583e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-17 03:37:06,361 unpacking tar archive at: mozilla-beta-92ec0fc8583e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.346357 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-17 03:37:06.592356) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 03:37:06.593325) ========= script_repo_revision: 92ec0fc8583e2a8bf38f0d8e98a3b78cda4eebaa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 03:37:06.594319) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 03:37:06.595124) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 03:37:06.615983) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-07-17 03:37:06.616673) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3gPCeXIk5f/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.GPbzJ7ZlO4/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 03:37:06 INFO - MultiFileLogger online at 20160717 03:37:06 in /builds/slave/test 03:37:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 03:37:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:37:06 INFO - {'append_to_log': False, 03:37:06 INFO - 'base_work_dir': '/builds/slave/test', 03:37:06 INFO - 'blob_upload_branch': 'Mozilla-Beta', 03:37:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:37:06 INFO - 'branch': 'Mozilla-Beta', 03:37:06 INFO - 'buildbot_json_path': 'buildprops.json', 03:37:06 INFO - 'config_files': ('talos/mac_config.py',), 03:37:06 INFO - 'default_actions': ('clobber', 03:37:06 INFO - 'read-buildbot-config', 03:37:06 INFO - 'download-and-extract', 03:37:06 INFO - 'populate-webroot', 03:37:06 INFO - 'create-virtualenv', 03:37:06 INFO - 'install', 03:37:06 INFO - 'run-tests'), 03:37:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:37:06 INFO - 'download_minidump_stackwalk': True, 03:37:06 INFO - 'download_symbols': 'ondemand', 03:37:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:37:06 INFO - 'tooltool.py': '/tools/tooltool.py', 03:37:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:37:06 INFO - '/tools/misc-python/virtualenv.py')}, 03:37:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:37:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:37:06 INFO - 'installer_path': 'installer.exe', 03:37:06 INFO - 'log_level': 'info', 03:37:06 INFO - 'log_name': 'talos', 03:37:06 INFO - 'log_to_console': True, 03:37:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:37:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:37:06 INFO - 'opt_config_files': (), 03:37:06 INFO - 'pip_index': False, 03:37:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:37:06 INFO - 'cmd': ('bash', 03:37:06 INFO - '-c', 03:37:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:37:06 INFO - 'enabled': True, 03:37:06 INFO - 'halt_on_failure': False, 03:37:06 INFO - 'name': 'check_screen_resolution'},), 03:37:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:37:06 INFO - 'cmd': ('bash', 03:37:06 INFO - '-c', 03:37:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:37:06 INFO - 'enabled': True, 03:37:06 INFO - 'halt_on_failure': False, 03:37:06 INFO - 'name': 'check_screen_resolution'},), 03:37:06 INFO - 'run_cmd_checks_enabled': True, 03:37:06 INFO - 'sps_profile': False, 03:37:06 INFO - 'sps_profile_interval': 0, 03:37:06 INFO - 'suite': 'dromaeojs-e10s', 03:37:06 INFO - 'system_bits': '32', 03:37:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:37:06 INFO - 'title': 't-yosemite-r7-0154', 03:37:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:37:06 INFO - 'use_talos_json': True, 03:37:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:37:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:37:06 INFO - 'work_dir': 'build'} 03:37:06 INFO - ##### 03:37:06 INFO - ##### Running clobber step. 03:37:06 INFO - ##### 03:37:06 INFO - Running pre-action listener: _resource_record_pre_action 03:37:06 INFO - Running main action method: clobber 03:37:06 INFO - rmtree: /builds/slave/test/build 03:37:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:37:08 INFO - Running post-action listener: _resource_record_post_action 03:37:08 INFO - ##### 03:37:08 INFO - ##### Running read-buildbot-config step. 03:37:08 INFO - ##### 03:37:08 INFO - Running pre-action listener: _resource_record_pre_action 03:37:08 INFO - Running main action method: read_buildbot_config 03:37:08 INFO - Using buildbot properties: 03:37:08 INFO - { 03:37:08 INFO - "project": "", 03:37:08 INFO - "product": "firefox", 03:37:08 INFO - "script_repo_revision": "production", 03:37:08 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 03:37:08 INFO - "repository": "", 03:37:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 03:37:08 INFO - "buildid": "20160717013154", 03:37:08 INFO - "pgo_build": "False", 03:37:08 INFO - "basedir": "/builds/slave/test", 03:37:08 INFO - "buildnumber": 17, 03:37:08 INFO - "slavename": "t-yosemite-r7-0154", 03:37:08 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 03:37:08 INFO - "platform": "yosemite_r7", 03:37:08 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 03:37:08 INFO - "branch": "mozilla-beta", 03:37:08 INFO - "repo_path": "releases/mozilla-beta", 03:37:08 INFO - "revision": "92ec0fc8583e2a8bf38f0d8e98a3b78cda4eebaa", 03:37:08 INFO - "stage_platform": "macosx64", 03:37:08 INFO - "builduid": "b29bf0c67b4642cdbf4ed5e6aa124749", 03:37:08 INFO - "slavebuilddir": "test" 03:37:08 INFO - } 03:37:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 03:37:08 INFO - Running post-action listener: _resource_record_post_action 03:37:08 INFO - ##### 03:37:08 INFO - ##### Running download-and-extract step. 03:37:08 INFO - ##### 03:37:08 INFO - Running pre-action listener: _resource_record_pre_action 03:37:08 INFO - Running main action method: download_and_extract 03:37:08 INFO - mkdir: /builds/slave/test/build/tests 03:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:37:08 INFO - https://queue.taskcluster.net/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:37:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 03:37:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 03:37:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 03:37:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 03:37:09 INFO - Downloaded 1165 bytes. 03:37:09 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 03:37:09 INFO - Using the following test package requirements: 03:37:09 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 03:37:09 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 03:37:09 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 03:37:09 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 03:37:09 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 03:37:09 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 03:37:09 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 03:37:09 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 03:37:09 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 03:37:09 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 03:37:09 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 03:37:09 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 03:37:09 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 03:37:09 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 03:37:09 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 03:37:09 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 03:37:09 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 03:37:09 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 03:37: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')]} 03:37:09 INFO - https://queue.taskcluster.net/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:37:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 03:37:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 03:37:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 03:37:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 03:37:13 INFO - Downloaded 18314782 bytes. 03:37:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:37:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:37:16 INFO - Return code: 0 03:37:16 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 03:37:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:37:16 INFO - https://queue.taskcluster.net/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:37:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 03:37:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 03:37:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 03:37:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 03:37:18 INFO - Downloaded 18314782 bytes. 03:37:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:37:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:37:22 INFO - Return code: 0 03:37: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')]} 03:37:22 INFO - https://queue.taskcluster.net/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:37:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 03:37:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 03:37:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 03:37:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 03:37:23 INFO - Downloaded 10840145 bytes. 03:37:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:37:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:37:23 INFO - Return code: 0 03:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:37:23 INFO - https://queue.taskcluster.net/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 03:37:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 03:37:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 03:37:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 03:37:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 03:37:26 INFO - Downloaded 86191767 bytes. 03:37:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 03:37:26 INFO - mkdir: /builds/slave/test/properties 03:37:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:37:26 INFO - Writing to file /builds/slave/test/properties/build_url 03:37:26 INFO - Contents: 03:37:26 INFO - build_url:https://queue.taskcluster.net/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 03:37:27 INFO - Running post-action listener: _resource_record_post_action 03:37:27 INFO - Running post-action listener: set_extra_try_arguments 03:37:27 INFO - ##### 03:37:27 INFO - ##### Running populate-webroot step. 03:37:27 INFO - ##### 03:37:27 INFO - Running pre-action listener: _resource_record_pre_action 03:37:27 INFO - Running main action method: populate_webroot 03:37:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:37:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:37:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:37:27 INFO - '--tppagecycles', 03:37:27 INFO - '7'], 03:37:27 INFO - 'tests': ['tsvgm']}}, 03:37:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:37:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:37:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:37:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:37:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:37:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:37:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:37:27 INFO - 'talos_options': ['--disable-e10s'], 03:37:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:37:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:37:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:37:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:37:27 INFO - 'talos_options': ['--disable-e10s'], 03:37:27 INFO - 'tests': ['damp', 'tps']}, 03:37:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:37:27 INFO - 'tests': ['damp', 'tps']}, 03:37:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:37:27 INFO - 'tests': ['dromaeo_dom']}, 03:37:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:37:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:37:27 INFO - 'tests': ['a11yr', 03:37:27 INFO - 'ts_paint', 03:37:27 INFO - 'tpaint', 03:37:27 INFO - 'sessionrestore', 03:37:27 INFO - 'sessionrestore_no_auto_restore', 03:37:27 INFO - 'tabpaint']}, 03:37:27 INFO - 'other-e10s': {'tests': ['a11yr', 03:37:27 INFO - 'ts_paint', 03:37:27 INFO - 'tpaint', 03:37:27 INFO - 'sessionrestore', 03:37:27 INFO - 'sessionrestore_no_auto_restore', 03:37:27 INFO - 'tabpaint']}, 03:37:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:37:27 INFO - 'ts_paint', 03:37:27 INFO - 'tpaint', 03:37:27 INFO - 'sessionrestore', 03:37:27 INFO - 'sessionrestore_no_auto_restore', 03:37:27 INFO - 'tabpaint']}, 03:37:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:37:27 INFO - 'ts_paint', 03:37:27 INFO - 'tpaint', 03:37:27 INFO - 'sessionrestore', 03:37:27 INFO - 'sessionrestore_no_auto_restore', 03:37:27 INFO - 'tabpaint']}, 03:37:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:37:27 INFO - 'tests': ['a11yr', 03:37:27 INFO - 'ts_paint', 03:37:27 INFO - 'tpaint', 03:37:27 INFO - 'sessionrestore', 03:37:27 INFO - 'sessionrestore_no_auto_restore', 03:37:27 INFO - 'tabpaint']}, 03:37:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:37:27 INFO - 'tests': ['a11yr', 03:37:27 INFO - 'ts_paint', 03:37:27 INFO - 'tpaint', 03:37:27 INFO - 'sessionrestore', 03:37:27 INFO - 'sessionrestore_no_auto_restore', 03:37:27 INFO - 'tabpaint']}, 03:37:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:37:27 INFO - 'tests': ['tsvgx', 03:37:27 INFO - 'tsvgr_opacity', 03:37:27 INFO - 'tart', 03:37:27 INFO - 'tscrollx', 03:37:27 INFO - 'cart']}, 03:37:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:37:27 INFO - 'tsvgr_opacity', 03:37:27 INFO - 'tart', 03:37:27 INFO - 'tscrollx', 03:37:27 INFO - 'cart']}, 03:37:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:37:27 INFO - 'talos_options': ['--disable-e10s'], 03:37:27 INFO - 'tests': ['tp5o']}, 03:37:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:37:27 INFO - 'tests': ['tp5o']}, 03:37:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:37:27 INFO - 'talos_options': ['--disable-e10s', 03:37:27 INFO - '--xperf_path', 03:37:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:37:27 INFO - 'tests': ['tp5n']}, 03:37:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:37:27 INFO - 'talos_options': ['--xperf_path', 03:37:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:37:27 INFO - 'tests': ['tp5n']}}, 03:37:27 INFO - 'talos.zip': {'path': '', 03:37:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:37:27 INFO - Running post-action listener: _resource_record_post_action 03:37:27 INFO - ##### 03:37:27 INFO - ##### Running create-virtualenv step. 03:37:27 INFO - ##### 03:37:27 INFO - Running pre-action listener: _resource_record_pre_action 03:37:27 INFO - Running main action method: create_virtualenv 03:37:27 INFO - Creating virtualenv /builds/slave/test/build/venv 03:37:27 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 03:37:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:37:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:37:27 INFO - Using real prefix '/tools/python27' 03:37:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:37:28 INFO - Installing distribute.............................................................................................................................................................................................done. 03:37:31 INFO - Installing pip.................done. 03:37:31 INFO - Return code: 0 03:37:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:37:31 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')]} 03:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:31 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')]} 03:37:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:31 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 0x1045aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045be420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe37358f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10444de00>, '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 0x1042b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104448b20>, '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.3gPCeXIk5f/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.GPbzJ7ZlO4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 03:37:31 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 03:37:31 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 03:37:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gPCeXIk5f/Render', 03:37:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:31 INFO - 'HOME': '/Users/cltbld', 03:37:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:31 INFO - 'LOGNAME': 'cltbld', 03:37:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:31 INFO - 'MOZ_NO_REMOTE': '1', 03:37:31 INFO - 'NO_EM_RESTART': '1', 03:37:31 INFO - 'PAGER': '/bin/cat', 03:37:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:31 INFO - 'PWD': '/builds/slave/test', 03:37:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:31 INFO - 'SHELL': '/bin/bash', 03:37:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GPbzJ7ZlO4/Listeners', 03:37:31 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 03:37:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:31 INFO - 'USER': 'cltbld', 03:37:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:31 INFO - 'XPC_FLAGS': '0x0', 03:37:31 INFO - 'XPC_SERVICE_NAME': '0', 03:37:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:31 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 03:37:31 INFO - Cleaning up... 03:37:31 INFO - Return code: 0 03:37:31 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:37:31 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')]} 03:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:31 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')]} 03:37:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:31 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 0x1045aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045be420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe37358f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10444de00>, '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 0x1042b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104448b20>, '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.3gPCeXIk5f/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.GPbzJ7ZlO4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 03:37:31 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 03:37:31 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 03:37:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gPCeXIk5f/Render', 03:37:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:31 INFO - 'HOME': '/Users/cltbld', 03:37:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:31 INFO - 'LOGNAME': 'cltbld', 03:37:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:31 INFO - 'MOZ_NO_REMOTE': '1', 03:37:31 INFO - 'NO_EM_RESTART': '1', 03:37:31 INFO - 'PAGER': '/bin/cat', 03:37:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:31 INFO - 'PWD': '/builds/slave/test', 03:37:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:31 INFO - 'SHELL': '/bin/bash', 03:37:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GPbzJ7ZlO4/Listeners', 03:37:31 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 03:37:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:31 INFO - 'USER': 'cltbld', 03:37:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:31 INFO - 'XPC_FLAGS': '0x0', 03:37:31 INFO - 'XPC_SERVICE_NAME': '0', 03:37:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:31 INFO - Downloading/unpacking psutil>=0.7.1 03:37:31 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 03:37:31 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 03:37:31 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 03:37:31 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 03:37:31 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 03:37:31 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 03:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:37:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:37:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:37:34 INFO - Installing collected packages: psutil 03:37:34 INFO - Running setup.py install for psutil 03:37:34 INFO - building 'psutil._psutil_osx' extension 03:37:34 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 03:37: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:37: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:37:35 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 03:37:35 INFO - building 'psutil._psutil_posix' extension 03:37:35 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 03:37:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:37:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:37:35 INFO - ^ 03:37:35 INFO - 1 warning generated. 03:37:35 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 03:37:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:37:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:37:35 INFO - Successfully installed psutil 03:37:35 INFO - Cleaning up... 03:37:36 INFO - Return code: 0 03:37:36 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:37: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')]} 03:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37: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')]} 03:37:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37: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 0x1045aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045be420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe37358f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10444de00>, '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 0x1042b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104448b20>, '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.3gPCeXIk5f/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.GPbzJ7ZlO4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 03:37: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 03:37: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 03:37:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gPCeXIk5f/Render', 03:37:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:36 INFO - 'HOME': '/Users/cltbld', 03:37:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:36 INFO - 'LOGNAME': 'cltbld', 03:37:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:36 INFO - 'MOZ_NO_REMOTE': '1', 03:37:36 INFO - 'NO_EM_RESTART': '1', 03:37:36 INFO - 'PAGER': '/bin/cat', 03:37:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:36 INFO - 'PWD': '/builds/slave/test', 03:37:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:36 INFO - 'SHELL': '/bin/bash', 03:37:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GPbzJ7ZlO4/Listeners', 03:37:36 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 03:37:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:36 INFO - 'USER': 'cltbld', 03:37:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:36 INFO - 'XPC_FLAGS': '0x0', 03:37:36 INFO - 'XPC_SERVICE_NAME': '0', 03:37:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:36 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:37: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 03:37: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 03:37: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 03:37: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 03:37: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 03:37: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 03:37:38 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:37:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:37:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:37:38 INFO - Installing collected packages: mozsystemmonitor 03:37:38 INFO - Running setup.py install for mozsystemmonitor 03:37:38 INFO - Successfully installed mozsystemmonitor 03:37:38 INFO - Cleaning up... 03:37:39 INFO - Return code: 0 03:37:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:37: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')]} 03:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37: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')]} 03:37:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37: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 0x1045aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045be420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe37358f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10444de00>, '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 0x1042b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104448b20>, '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.3gPCeXIk5f/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.GPbzJ7ZlO4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 03:37: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 03:37: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 03:37:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gPCeXIk5f/Render', 03:37:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:39 INFO - 'HOME': '/Users/cltbld', 03:37:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:39 INFO - 'LOGNAME': 'cltbld', 03:37:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:39 INFO - 'MOZ_NO_REMOTE': '1', 03:37:39 INFO - 'NO_EM_RESTART': '1', 03:37:39 INFO - 'PAGER': '/bin/cat', 03:37:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:39 INFO - 'PWD': '/builds/slave/test', 03:37:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:39 INFO - 'SHELL': '/bin/bash', 03:37:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GPbzJ7ZlO4/Listeners', 03:37:39 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 03:37:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:39 INFO - 'USER': 'cltbld', 03:37:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:39 INFO - 'XPC_FLAGS': '0x0', 03:37:39 INFO - 'XPC_SERVICE_NAME': '0', 03:37:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:39 INFO - Downloading/unpacking blobuploader==1.2.4 03:37: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 03:37: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 03:37: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 03:37: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 03:37: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 03:37: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 03:37:41 INFO - Downloading blobuploader-1.2.4.tar.gz 03:37:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:37:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:37:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:37:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:37: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 03:37: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 03:37: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 03:37: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 03:37: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 03:37: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 03:37:42 INFO - Downloading docopt-0.6.1.tar.gz 03:37:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:37:43 INFO - Installing collected packages: blobuploader, requests, docopt 03:37:43 INFO - Running setup.py install for blobuploader 03:37:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:37:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:37:43 INFO - Running setup.py install for requests 03:37:43 INFO - Running setup.py install for docopt 03:37:43 INFO - Successfully installed blobuploader requests docopt 03:37:43 INFO - Cleaning up... 03:37:43 INFO - Return code: 0 03:37:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:37:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:37:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:43 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 0x1045aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045be420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe37358f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10444de00>, '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 0x1042b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104448b20>, '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.3gPCeXIk5f/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.GPbzJ7ZlO4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 03:37:43 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 03:37:43 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 03:37:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gPCeXIk5f/Render', 03:37:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:44 INFO - 'HOME': '/Users/cltbld', 03:37:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:44 INFO - 'LOGNAME': 'cltbld', 03:37:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:44 INFO - 'MOZ_NO_REMOTE': '1', 03:37:44 INFO - 'NO_EM_RESTART': '1', 03:37:44 INFO - 'PAGER': '/bin/cat', 03:37:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:44 INFO - 'PWD': '/builds/slave/test', 03:37:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:44 INFO - 'SHELL': '/bin/bash', 03:37:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GPbzJ7ZlO4/Listeners', 03:37:44 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 03:37:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:44 INFO - 'USER': 'cltbld', 03:37:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:44 INFO - 'XPC_FLAGS': '0x0', 03:37:44 INFO - 'XPC_SERVICE_NAME': '0', 03:37:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:37:44 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-tjZ6I3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:37:44 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-jGLapO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:37:44 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-kpWXiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:37:44 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-LxE2K8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:37:44 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-KiR5UU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:37:44 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-NFEaPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:37:44 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-iTT6cu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:37:44 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-7vtnsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:37:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-btJTTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:37:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Ses1FD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:37:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-2S6n5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:37:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-zfZb4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:37:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-tlyuoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:37:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-HJGc85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:37:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-bGNUqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:37:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Av8oWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:37:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-uLAJek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:37:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:37:46 INFO - Running setup.py install for manifestparser 03:37:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:37:46 INFO - Running setup.py install for mozcrash 03:37:46 INFO - Running setup.py install for mozdebug 03:37:46 INFO - Running setup.py install for mozdevice 03:37:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:37:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:37:46 INFO - Running setup.py install for mozfile 03:37:46 INFO - Running setup.py install for mozhttpd 03:37:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:37:46 INFO - Running setup.py install for mozinfo 03:37:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:37:47 INFO - Running setup.py install for mozInstall 03:37:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:37:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:37:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:37:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:37:47 INFO - Running setup.py install for mozleak 03:37:47 INFO - Running setup.py install for mozlog 03:37:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:37:47 INFO - Running setup.py install for moznetwork 03:37:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:37:47 INFO - Running setup.py install for mozprocess 03:37:47 INFO - Running setup.py install for mozprofile 03:37:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:37:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:37:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:37:47 INFO - Running setup.py install for mozrunner 03:37:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:37:48 INFO - Running setup.py install for mozscreenshot 03:37:48 INFO - Running setup.py install for moztest 03:37:48 INFO - Running setup.py install for mozversion 03:37:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:37:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:37:48 INFO - Cleaning up... 03:37:48 INFO - Return code: 0 03:37:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:37:48 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')]} 03:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:48 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')]} 03:37:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:48 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 0x1045aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045be420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe37358f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10444de00>, '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 0x1042b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104448b20>, '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.3gPCeXIk5f/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.GPbzJ7ZlO4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 03:37:48 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 03:37:48 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 03:37:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gPCeXIk5f/Render', 03:37:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:48 INFO - 'HOME': '/Users/cltbld', 03:37:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:48 INFO - 'LOGNAME': 'cltbld', 03:37:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:48 INFO - 'MOZ_NO_REMOTE': '1', 03:37:48 INFO - 'NO_EM_RESTART': '1', 03:37:48 INFO - 'PAGER': '/bin/cat', 03:37:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:48 INFO - 'PWD': '/builds/slave/test', 03:37:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:48 INFO - 'SHELL': '/bin/bash', 03:37:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GPbzJ7ZlO4/Listeners', 03:37:48 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 03:37:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:48 INFO - 'USER': 'cltbld', 03:37:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:48 INFO - 'XPC_FLAGS': '0x0', 03:37:48 INFO - 'XPC_SERVICE_NAME': '0', 03:37:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:37:48 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-VhpMoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:37:48 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)) 03:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:37:48 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-wGK0Gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:37: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)) 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:37:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-hLY6Ie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:37: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)) 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:37:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ffXcrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:37: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)) 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:37:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-wBIdyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:37: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)) 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:37:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-cEK6ju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:37: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)) 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:37:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-vVvrsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:37:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:37:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-u9gqna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:37:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:37:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-QIklFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:37:49 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)) 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:37:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-IWA6w5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:37:49 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)) 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:37:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-zhcESe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:37:49 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)) 03:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:37:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-H1AYoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:37: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)) 03:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:37:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-HROA8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:37: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)) 03:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:37:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-XVzLoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:37: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)) 03:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:37:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-2fnmSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:37: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)) 03:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:37:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-fKxuTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:37: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)) 03:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:37:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-jX8AGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:37:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:37:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:53 INFO - Downloading blessings-1.6.tar.gz 03:37:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:37:53 INFO - Installing collected packages: blessings 03:37:53 INFO - Running setup.py install for blessings 03:37:53 INFO - Successfully installed blessings 03:37:53 INFO - Cleaning up... 03:37:53 INFO - Return code: 0 03:37:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:37:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:37:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:37:53 INFO - Reading from file tmpfile_stdout 03:37:53 INFO - Current package versions: 03:37:53 INFO - blessings == 1.6 03:37:53 INFO - blobuploader == 1.2.4 03:37:53 INFO - docopt == 0.6.1 03:37:53 INFO - manifestparser == 1.1 03:37:53 INFO - mozInstall == 1.12 03:37:53 INFO - mozcrash == 0.17 03:37:53 INFO - mozdebug == 0.1 03:37:53 INFO - mozdevice == 0.48 03:37:53 INFO - mozfile == 1.2 03:37:53 INFO - mozhttpd == 0.7 03:37:53 INFO - mozinfo == 0.9 03:37:53 INFO - mozleak == 0.1 03:37:53 INFO - mozlog == 3.1 03:37:53 INFO - moznetwork == 0.27 03:37:53 INFO - mozprocess == 0.22 03:37:53 INFO - mozprofile == 0.28 03:37:53 INFO - mozrunner == 6.11 03:37:53 INFO - mozscreenshot == 0.1 03:37:53 INFO - mozsystemmonitor == 0.0 03:37:53 INFO - moztest == 0.7 03:37:53 INFO - mozversion == 1.4 03:37:53 INFO - psutil == 3.1.1 03:37:53 INFO - requests == 1.2.3 03:37:53 INFO - wsgiref == 0.1.2 03:37:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:53 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 0x1045aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045be420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe37358f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10444de00>, '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 0x1042b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104448b20>, '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.3gPCeXIk5f/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.GPbzJ7ZlO4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 03:37:53 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 03:37:53 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 03:37:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gPCeXIk5f/Render', 03:37:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:53 INFO - 'HOME': '/Users/cltbld', 03:37:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:53 INFO - 'LOGNAME': 'cltbld', 03:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:53 INFO - 'MOZ_NO_REMOTE': '1', 03:37:53 INFO - 'NO_EM_RESTART': '1', 03:37:53 INFO - 'PAGER': '/bin/cat', 03:37:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:53 INFO - 'PWD': '/builds/slave/test', 03:37:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:53 INFO - 'SHELL': '/bin/bash', 03:37:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GPbzJ7ZlO4/Listeners', 03:37:53 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 03:37:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:53 INFO - 'USER': 'cltbld', 03:37:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:53 INFO - 'XPC_FLAGS': '0x0', 03:37:53 INFO - 'XPC_SERVICE_NAME': '0', 03:37:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:37:53 INFO - Cleaning up... 03:37:53 INFO - Return code: 0 03:37:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1045aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045be420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe37358f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10444de00>, '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 0x1042b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104448b20>, '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.3gPCeXIk5f/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.GPbzJ7ZlO4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 03:37:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:37:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:37:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gPCeXIk5f/Render', 03:37:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:53 INFO - 'HOME': '/Users/cltbld', 03:37:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:53 INFO - 'LOGNAME': 'cltbld', 03:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:53 INFO - 'MOZ_NO_REMOTE': '1', 03:37:53 INFO - 'NO_EM_RESTART': '1', 03:37:53 INFO - 'PAGER': '/bin/cat', 03:37:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:53 INFO - 'PWD': '/builds/slave/test', 03:37:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:53 INFO - 'SHELL': '/bin/bash', 03:37:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GPbzJ7ZlO4/Listeners', 03:37:53 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 03:37:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:53 INFO - 'USER': 'cltbld', 03:37:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:53 INFO - 'XPC_FLAGS': '0x0', 03:37:53 INFO - 'XPC_SERVICE_NAME': '0', 03:37:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:54 INFO - Downloading/unpacking jsonschema 03:37: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 03:37: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 03:37: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 03:37: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 03:37: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 03:37: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 03:37:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:37:56 INFO - Downloading/unpacking functools32 (from jsonschema) 03:37:56 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 03:37:56 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 03:37:56 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 03:37:56 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 03:37:56 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 03:37:56 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 03:37:56 INFO - Downloading functools32-3.2.3-2.tar.gz 03:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:37:57 INFO - warning: no files found matching '*.txt' 03:37:57 INFO - no previously-included directories found matching 'build' 03:37:57 INFO - no previously-included directories found matching 'dist' 03:37:57 INFO - no previously-included directories found matching '.git*' 03:37:57 INFO - Installing collected packages: jsonschema, functools32 03:37:57 INFO - Running setup.py install for functools32 03:37:57 INFO - warning: no files found matching '*.txt' 03:37:57 INFO - no previously-included directories found matching 'build' 03:37:57 INFO - no previously-included directories found matching 'dist' 03:37:57 INFO - no previously-included directories found matching '.git*' 03:37:57 INFO - Successfully installed jsonschema functools32 03:37:57 INFO - Cleaning up... 03:37:57 INFO - Return code: 0 03:37:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:37: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')]} 03:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37: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')]} 03:37:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37: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 0x1045aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045be420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe37358f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10444de00>, '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 0x1042b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104448b20>, '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.3gPCeXIk5f/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.GPbzJ7ZlO4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 03:37: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 03:37: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 03:37:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gPCeXIk5f/Render', 03:37:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:57 INFO - 'HOME': '/Users/cltbld', 03:37:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:57 INFO - 'LOGNAME': 'cltbld', 03:37:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:57 INFO - 'MOZ_NO_REMOTE': '1', 03:37:57 INFO - 'NO_EM_RESTART': '1', 03:37:57 INFO - 'PAGER': '/bin/cat', 03:37:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:57 INFO - 'PWD': '/builds/slave/test', 03:37:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:57 INFO - 'SHELL': '/bin/bash', 03:37:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GPbzJ7ZlO4/Listeners', 03:37:57 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 03:37:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:57 INFO - 'USER': 'cltbld', 03:37:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:57 INFO - 'XPC_FLAGS': '0x0', 03:37:57 INFO - 'XPC_SERVICE_NAME': '0', 03:37:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:57 INFO - Downloading/unpacking flake8 03:37: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 03:37: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 03:37: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 03:37: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 03:37: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 03:37: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 03:38:00 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:38:00 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:38: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 03:38: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 03:38: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 03:38: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 03:38: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 03:38: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 03:38:00 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:38:00 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:38: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 03:38: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 03:38: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 03:38: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 03:38: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 03:38: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 03:38:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:38: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 03:38: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 03:38: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 03:38: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 03:38: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 03:38: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 03:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:38:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:38:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:38:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:38:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:38:01 INFO - no previously-included directories found matching 'docs/_build' 03:38:01 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:38:01 INFO - Running setup.py install for pep8 03:38:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:38:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:38:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:38:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:38:01 INFO - no previously-included directories found matching 'docs/_build' 03:38:01 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:38:01 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:38:01 INFO - Cleaning up... 03:38:01 INFO - Return code: 0 03:38:01 INFO - Running post-action listener: _resource_record_post_action 03:38:01 INFO - Running post-action listener: _start_resource_monitoring 03:38:01 INFO - Starting resource monitoring. 03:38:01 INFO - ##### 03:38:01 INFO - ##### Running install step. 03:38:01 INFO - ##### 03:38:01 INFO - Running pre-action listener: _resource_record_pre_action 03:38:01 INFO - Running main action method: install 03:38:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:38:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:38:01 INFO - Reading from file tmpfile_stdout 03:38:01 INFO - Detecting whether we're running mozinstall >=1.0... 03:38:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:38:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:38:02 INFO - Reading from file tmpfile_stdout 03:38:02 INFO - Output received: 03:38:02 INFO - Usage: mozinstall [options] installer 03:38:02 INFO - Options: 03:38:02 INFO - -h, --help show this help message and exit 03:38:02 INFO - -d DEST, --destination=DEST 03:38:02 INFO - Directory to install application into. [default: 03:38:02 INFO - "/builds/slave/test"] 03:38:02 INFO - --app=APP Application being installed. [default: firefox] 03:38:02 INFO - mkdir: /builds/slave/test/build/application 03:38:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:38:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 03:38:23 INFO - Reading from file tmpfile_stdout 03:38:23 INFO - Output received: 03:38:23 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 03:38:23 INFO - Running post-action listener: _resource_record_post_action 03:38:23 INFO - ##### 03:38:23 INFO - ##### Running run-tests step. 03:38:23 INFO - ##### 03:38:23 INFO - Running pre-action listener: _resource_record_pre_action 03:38:23 INFO - Running main action method: run_tests 03:38:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:38:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:38:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:38:23 INFO - 2016-07-17 03:38:23.864 screenresolution[1638:7481] starting screenresolution argv=screenresolution get 03:38:23 INFO - 2016-07-17 03:38:23.866 screenresolution[1638:7481] Display 0: 1600x1200x32@60 03:38:23 INFO - 2016-07-17 03:38:23.871 screenresolution[1639:7484] starting screenresolution argv=screenresolution list 03:38:23 INFO - Available Modes on Display 0 03:38:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:38:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:38:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:38:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:38:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:38:24 INFO - Intel Iris: 03:38:24 INFO - Chipset Model: Intel Iris 03:38:24 INFO - Type: GPU 03:38:24 INFO - Bus: Built-In 03:38:24 INFO - VRAM (Dynamic, Max): 1536 MB 03:38:24 INFO - Vendor: Intel (0x8086) 03:38:24 INFO - Device ID: 0x0a2e 03:38:24 INFO - Revision ID: 0x0009 03:38:24 INFO - Displays: 03:38:24 INFO - Display: 03:38:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:38:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:38:24 INFO - Display Serial Number: 1600x1200 60 03:38:24 INFO - Main Display: Yes 03:38:24 INFO - Mirror: Off 03:38:24 INFO - Online: Yes 03:38:24 INFO - Rotation: Supported 03:38:24 INFO - Return code: 0 03:38:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:38:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:38:24 INFO - Python 2.7.3 03:38:24 INFO - Return code: 0 03:38:24 INFO - grabbing minidump binary from tooltool 03:38: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')]} 03:38:24 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 0x10444de00>, '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 0x1042b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104448b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:38:24 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 03:38:24 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 03:38:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:38:24 INFO - Return code: 0 03:38:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:38:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:38:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:38:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:38:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:38:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:38:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:38:25 INFO - Return code: 0 03:38:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0154 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:38:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3gPCeXIk5f/Render', 03:38:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:38:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:38:25 INFO - 'HOME': '/Users/cltbld', 03:38:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:38:25 INFO - 'LOGNAME': 'cltbld', 03:38:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:38:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:25 INFO - 'MOZ_NO_REMOTE': '1', 03:38:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:38:25 INFO - 'NO_EM_RESTART': '1', 03:38:25 INFO - 'PAGER': '/bin/cat', 03:38:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:25 INFO - 'PWD': '/builds/slave/test', 03:38:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:38:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:38:25 INFO - 'SHELL': '/bin/bash', 03:38:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GPbzJ7ZlO4/Listeners', 03:38:25 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 03:38:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:38:25 INFO - 'USER': 'cltbld', 03:38:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:38:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:25 INFO - 'XPC_FLAGS': '0x0', 03:38:25 INFO - 'XPC_SERVICE_NAME': '0', 03:38:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:38:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:38:25 INFO - mozversion application_buildid: 20160717013154 03:38:25 INFO - mozversion application_changeset: 92ec0fc8583e2a8bf38f0d8e98a3b78cda4eebaa 03:38:25 INFO - mozversion application_display_name: Firefox 03:38:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:38:25 INFO - mozversion application_name: Firefox 03:38:25 INFO - mozversion application_remotingname: firefox 03:38:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 03:38:25 INFO - mozversion application_vendor: Mozilla 03:38:25 INFO - mozversion application_version: 48.0 03:38:25 INFO - mozversion platform_buildid: 20160717013154 03:38:25 INFO - mozversion platform_changeset: 92ec0fc8583e2a8bf38f0d8e98a3b78cda4eebaa 03:38:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 03:38:25 INFO - mozversion platform_version: 48.0 03:38:25 INFO - using testdate: 1468751905 03:38:25 INFO - actual date: 1468751905 03:38:25 INFO - starting webserver on 'localhost:49283' 03:38:25 INFO - SUITE-START | Running 2 tests 03:38:25 INFO - TEST-START | dromaeo_css 03:38:25 INFO - operating with platform_type : mac_ 03:38:25 INFO - Initialising browser for dromaeo_css test... 03:38:25 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpwuSfq7/profile http://localhost:49283/getInfo.html) 03:38:28 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 03:38:28 INFO - PROCESS | 1657 | colorDepth:24 03:38:28 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 03:38:28 INFO - PROCESS | 1657 | __metrics 03:38:28 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 03:38:29 INFO - TEST-INFO | 1657: exit 0 03:38:29 INFO - Browser initialized. 03:38:29 INFO - Running cycle 1/1 for dromaeo_css test... 03:38:29 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpwuSfq7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:38:41 INFO - PROCESS | 1661 | RSS: Main: 170831872 03:38:41 INFO - PROCESS | 1661 | 03:39:30 INFO - PROCESS | 1661 | 1468751970650 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] 03:41:40 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49283/tests/dromaeo/cssquery-ext.html) 03:41:42 INFO - PROCESS | 1661 | RSS: Main: 182824960 03:41:42 INFO - PROCESS | 1661 | 03:44:44 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-ext.html (next: http://localhost:49283/tests/dromaeo/cssquery-jquery.html) 03:44:44 INFO - PROCESS | 1661 | RSS: Main: 192491520 03:44:44 INFO - PROCESS | 1661 | 03:47:46 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49283/tests/dromaeo/cssquery-mootools.html) 03:47:47 INFO - PROCESS | 1661 | RSS: Main: 194420736 03:47:47 INFO - PROCESS | 1661 | 03:50:48 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49283/tests/dromaeo/cssquery-prototype.html) 03:50:49 INFO - PROCESS | 1661 | RSS: Main: 198250496 03:50:49 INFO - PROCESS | 1661 | 03:53:51 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49283/tests/dromaeo/cssquery-yui.html) 03:53:52 INFO - PROCESS | 1661 | RSS: Main: 197427200 03:53:52 INFO - PROCESS | 1661 | 03:56:54 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-yui.html (next: http://localhost:49283/tests/dromaeo/cssquery-dojo.html) 03:56:54 INFO - PROCESS | 1661 | RSS: Main: 202076160 03:56:54 INFO - PROCESS | 1661 | 03:56:54 INFO - PROCESS | 1661 | __start_tp_report 03:56:54 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 03:56:54 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 03:56:54 INFO - PROCESS | 1661 | |i|pagename|runs| 03:56:54 INFO - PROCESS | 1661 | |0;dojo.html;3012.0361484325026;3189.6012998375204;3470.7570470067108;3748.831302591384;3759.3045286621978;5961.195238642787;6052.455279024887;6080.72636731347;6123.295820980588;6123.846219197111;5944.583879128452;6067.9393206067925;6089.29973053099;6124.295705993812;6136.631802091878;5435.918461223085;5469.699166545838;5477.5070243678065;5524.613277070606;5560.499555040045;4662.90674186516;4895.192293271606;4923.926141107886;5409.134538473845;5430.239766432703;5487.341519017704;5495.395506494282;5511.090670039459;5516.558675305986;5517;5448.8365349039195;5463.399026107125;5492.176173573976;5525.2264682944015;5562.110062390038;4248.681348898814;5387.057264978613;5505.339359276913;5505.981393442186;5510.448955104497;5170.896582068375;6062.8787424251705;6070.544709146831;6085.513158947274;6130.478909292716;6283.371662833726;6311.179546658951;6359.046143078529;6363.300036995926;6421.903671444932;6430.421262086388;6435.131257280254;6438.291787903374;6441.130447389589;6458.321876202978;5376.843978544593;5996.5202783777195;6068.878622427526;6106.603070800384;6205.6897155142515;6364.968175159094;6401.263854656681;6461.160049193669;6511.088447617338;6544.247411547638;6321.810345689637;6339.334369891188;6372.84067898297;6381.968090159519;6400.455961243253;6307.990721484541;6442.130312407859;6446.033095035782;6453.193350831146;6459.967700161469;5241.633085684041;6296.212973378328;6330.177076980055;6353.777617783356;6354.3963323484195;6321.1782468278825;6367.872642547123;6371.299157092716;6387.489000879918;6462.741490340334;5047.343845300087;5066.493350664904;5092.668976516515;5129.9230511542355;5193.272941788153;4588.128255631419;4643.0017546227045;4651.348811166439;4653.139169253699;4695.436547614308;6168.414000669929;6169.321374648785;6230.875382492415;6234.815385076821;6245.125682404467;6289.937100629027;6381.361863813581;6427.871442571122;6449.290578036413;6455.321041767614;6037.969810150921;6135.477655820289;6228.813135605939;6232.18981532407;6238.095476155932;5668.404817494186;5682.801101961411;5689.203511508392;5692.288463942007;5695.316562012585;6501.2198536175965;6618.437432818168;6661.300563440769;6721.563098398588;6732.4950628703045;6426.260979987362;6486.254080780582;6491.1236983008575;6491.7403303869205;6508.023796430573;6668.499862510234;6721.428678562182;6725.865482690417;6746.055552222595;6770.796876093725;6267.905981410374;6292.653904035322;6355.332690067661;6404.135441715495;6412.775552855627;6332.176817013758;6437.098806167047;6453.225612926479;6463.095166676575;6484.416402523795;6326.968365158329;6332.936670633235;6342.460890824332;6344.714487847966;6353.491720662243;6231.844203894939;6235.625862448268;6249.937500624936;6254.6247225166635;6346.587471814318;6252.718627661675;6280.748770049329;6292.52806039558;6309.085182648399;6337.556371054166;6218.720157593009;6233.532485063548;6282.277538083179;6308.810735677937;6321.589096708699;6190.226221722285;6286.842828929313;6313.526485513697;6316.873662526632;6330.588511746721 03:56:54 INFO - PROCESS | 1661 | |1;ext.html;552.5689961829773;557.1197507937459;568.9978502492795;570.4580648384031;582.554345925367;30540.541891871613;39191;39310;39319;39361.80319098401;42885.57114428855;42921.356179657254;43393.34909976353;43395.13209735811;43420.782896085555;9836.262280328987;9897;9921.107100360967;9928.503574821267;10038.749031274203;8640.567971601395;9856.507174641276;9942.801143977116;10005.349652272615;10025.548850301717;9880.456574888378;9900.356476828983;9911.256655750847;9929.156021738161;9934.205263578933;8864.246539044188;9637.37357071788;9852.408855468659;9929.404235745878;9961.153301969342;9801.166900813369;9833.950830245803;9887.901120988841;9904.455254960974;9956.303058785888;39359.40960885589;39452;39521.60478395208;39977.80011099955;40095.7995210025;3295.555100061509;3420.1791570023265;3471.288385880888;3509.736769742254;3525.7708248963736;28832;29192;29244.853775731197;29349.853250733824;29536.113916582537;2828.5474324108045;2837.7729781617422;2844.871980760871;2878.827270363785;2880.510313246753;480870;495487;497970;499165;499956;38282.80858595689;62325;62565.68717156385;62574.68712656407;62759;37099.62900370962;37818.81090594529;38097.61902380996;38175;38293.80853095717;14682.192479413734;15122.924385378003;15884.88805783584;15944.920275398548;16329.183540823149;11737.530498780125;13240.933795331155;13467.259300738553;13662.18026918388;13692.794608080887;7183.92816071843;8457.746267611981;8510.65957361711;8572.957135214285;8575.699850505202;3090.5055191169304;3440.9505100944452;3550.751447398657;3615.1142969972525;3640.7997560133917;997.8253805583994;1068.1775033224374;1125.5160281079052;1168.3807581981564;1171.5313874450287;29938.40123197524;30916.53625195624;31051.68948310488;31095.222619434695;31386;1044.4777611194402;1204.6506513111274;1234.216272666863;1235.320573684488;1250.474800583757;13810.135631689795;15108.471203507825;15677.216139193222;15875.364985400454;15901.52295431139;667166;669758;676925;678497;680523;60168;61228.38771612316;62196.68901655463;62361;62376;1579.3287852662572;1585.4371698047257;1594.6172918499476;1601.1513897634272;1629.9266533006044;24854.875725621256;25356.619650704884;25789.87105064538;30664.693353067076;31146.68853311438;52441;52488.47511524836;52570.21144682756;52881.20678189907;53227.66930826761;453254;455196;456525;457486.7125664484;458496;27433.039843605377;27618;27904.44191116207;27964.021259255827;43932.560674392844;43749;44554.77722611366;44901;45096.77451612721;45482.31776523421;36981.260374792895;36994.815025925775;37035;37217.88346349614;37266.62733372632;37440.06399839917;37734.81132594319;37815.05462363463;37898.86303410902;38644;36040.63959360373;38604.03489912662;39306.410403843394;39800.40299395569;40592.1881562373;32068.679313207504;32236;32249.51625725569;32537.511937320487;32701.258317543346;20627;24641.260762177164;24786.132485362927;24897.004119835005;24939.50362978227 03:56:54 INFO - PROCESS | 1661 | |2;jquery.html;350.5793048341989;353.33220213795914;418.61905665844085;423.7309308589047;434.3593200029956;14432.855671443282;21031.68452473212;21656.350309490703;21717.457063573398;21787.019584118712;20734.792652073476;21110;21758;22395.99218035188;22440;5843.123531470271;6060.393960603938;6178.907316390258;6183.319834818167;6386.10594516768;5598.4961353478175;6116.235470566179;6140.938590614103;6166.198394208769;6241.625502469844;5269.710165940872;6156.56904016719;6218.378162183768;6226.968865155668;6234.594751341171;6474.74101035958;6737.966310168466;6803.965980170067;6822.147231596051;6885.414739747128;4610.76946152691;5868.677222752747;6046.123312119762;6089.543284253655;6113.694315284218;13821.239831809347;14249.71500569993;15240.847591524054;22418;27697.723022769715;2128.5849259394427;2377.239283429303;2433.4038160650753;2548.210054882992;2780.4439112177647;8199.508029518189;8728.083551227157;9969.35199212049;10198.16433042052;10580.841287380696;1810.900400477973;1828.6616975859376;1857.5820440400937;1882.5387779993853;1888.169205549554;111882;123680;123974;125699;126986.99999999908;10058.245631577662;11884.702882427835;11954.16320857549;12178.208416452902;12190.57332993341;12427.93786031064;15248.618784530254;15658.982166159163;15717.056976581212;15725.842741572667;8884.955575222211;9123.406978546373;9151.588178531982;9227.26181905446;9261.49061801594;9014.602736575842;9100.408473449337;9106.31702622306;9200.377892300796;9229;4084.959150408458;4698.778317637439;4809.110314591811;4833.73414462208;4856.1016212000895;1850.4171186076344;1972.0534143611146;1980.5345743750206;2047.4950911080343;2115.249086574244;778.2334400615389;954.2127744610585;1011.3173607814696;1042.9018670027244;1070.5610699613278;20566.07452664634;20965.79034209669;20988.790112098988;21225.938703064785;21294.574108517743;1028.2699283508794;1031.6956497833157;1058.5659879449543;1065.4779158212575;1078.719532921446;9602.279829012852;10790.460476976119;10847.566097356017;11203.60787372438;11224.66326010221;617649;619600;626645.7335426678;642563;682196;119447.40276298736;126835.36582317029;127622;128287;129700;1048.6172547020283;1054.267284237463;1063.7712891728315;1064.8988346107108;1072.1958531101673;17876.55308617295;18379.90810045941;18421;18559.628807424047;18635.44093677192;50431;52132;52509.94980100453;53792.462075378746;55737;68120;68375.65812170907;71816.64091679717;72356.63821681068;72871;6925.896111558432;8300.87548686758;8519.74440766778;8541.615627296662;8640.611172497378;9391.953040234755;12517.624471265877;13021.609351719464;13369.398377072861;13443.932780336036;11335.716607084889;11666.88333116658;11758.3532905691;11794.351310677996;12124.93937530342;10763.838542421714;11209.831852522382;11435.885641143816;11734.941325293661;11857.940710296394;9937.552810123707;10219.620351252486;11822.940885295518;11939.52241910314;12260.26438413698;8876.511791851251;12079.335636540074;12327.383630818602;12847.29339886278;12978.610641680765;11511.19421640477;12169.69575760613;12330;12374.876251237372;12403.131780775258 03:56:54 INFO - PROCESS | 1661 | |3;mootools.html;1442.3653592419334;1650.1254335202339;1892.6593213221615;2029.168041103147;2083.5312054787664;8329.58352082395;8979.775505612362;9199.402038867469;9373.390729602579;9389.483578403186;9165.404248723828;9206.217471514929;9268.397554158975;9309.581068851901;9373.297002724788;2026.8378529717588;2424.4787370715294;2538.657281267024;2538.923832285034;2555.0801711383933;2540.187140115164;2544.5419824431597;2545.4272788622598;2546.1597672767944;2591;2568.3579105223694;2571.6014017827265;2591.1578736910487;2612.8040396970305;2618.2145356393103;2449.448874003353;2494.762997515233;2527.5576774064557;2563.0132399026324;2623.8819253133656;2571.7942564594973;2591.896324147032;2604.4530648563823;2605.882735276917;2608.7782538484253;9193.678221262291;9209.861852072225;9452;9505.524723763785;9553.331266811327;5624.718764061821;5656.321241451053;5677.659340439587;5690.231818704463;5717.599768016241;3225.048610659861;3590.874319398835;3745.5130832991535;3746.063484128968;3757.6805971492454;1063.8936106389376;1086.1202426034938;1087.6410784441196;1089.5696200000955;1091.3670071358592;12423.192492487959;12514.624561263177;12686.936565317206;12697.93651031739;12706.04704647155;4404.361367601679;4970.3290055842;5035.1188542005;5074.314967479417;5091.66904151229;10394.48027598617;10627.84058239127;10632.946835265775;10671.466426678635;10737.409442480739;5535.916961245584;5580.581456390787;5598.944010559923;5719.7712091516705;5745.13822926556;4605.838795642137;5026.773795179226;5031.396232452129;5079.390473143172;5187.429382767892;3546.964530354715;3797.9430308545393;4191.350340697197;4280.571942805756;4369.104333611602;1255.604484587352;1369.8904087672963;1407.0853944935668;1429.0639631041684;1431.1556181852757;857.9270761985301;933.2906990687127;1006.2000709436016;1006.2050979725934;1010.1413798271558;6007.36922623127;6039.8792024159275;6199.721012554446;6240.719167637377;6256.311805701369;989.8267803134422;1000.1498726082772;1031.3090229546222;1040.266612038511;1068.4817863336275;3947.2697550953167;4123.773192474383;4149.460570125864;4196.769177695256;4371.256886329332;11042.116630669527;11080.501377438035;11097.056750176325;11246.212765106528;11589.188756787113;10755.193360497993;10887.727806804893;11166.609168679057;11305.604303849326;11307.604233851776;8286.668533258722;8457.957710211409;8620.568971551398;8680.522571258642;8718.43330183536;5093.23601459784;5270.919461510458;5474.288342515448;6079.787207447718;6177.62934223948;9844.409335439897;9971.551280192263;9988.350757200758;10007.34952228132;10175.796484070424;9293.72118836436;10008.699739007841;10276.588936442458;10293.43386113771;10420.166386689198;7140.7857764267155;7359.705611775683;7679.884801727867;7770.145284018867;7862;8734.213920747163;8757.430767000125;8767.824643507221;8812;8829.514376709343;7414;7713.382929365525;7737.613119344122;7842.64708088126;7863.724769633035;7437.851242975218;7657.042869641295;7670.194629563815;7670.27132422419;7757.72847950319;7331.890021649786;7342.5227360221415;7371.889421658787;7378.852422951618;7379;5020.523050310215;5055.974720126376;5101.464346243591;5226.843194704013;5227.581793456438;5041.117804384291;5085.262636917701;5130.538251557377;5152.02111598795;5250.868728281824 03:56:54 INFO - PROCESS | 1661 | |4;prototype.html;338.5024014698393;339.66373290442453;341.57473944938556;347.3296537682273;351.0574108518629;5703.686297253649;7090.610016449094;7105.786826395203;7150.141982962039;7243.6378181090995;5297.523222909937;7004.719811207546;7033.8944915826305;7050.85898282034;7060.046893669366;4980.651354405193;5079.187330027178;5198.246254293125;5204.479552044784;5219.138842091051;5137.794488220486;5191.948080519203;5214.348206474191;5223;5242.842714718565;5180.844574662748;5212.4266330703595;5214.270002199695;5219.373675158968;5220.373555173384;5191.610629202806;5223.71269580173;5229.529342359187;5235.55497782689;5269.762860671241;3660.2862441823854;4491.30384790358;4491.955080449186;4498.572635599613;4499.1451624191295;6615.669216539154;6768.357006084463;6807.829804254884;6835.7949261522235;6887;6068.059450785136;6108.602940808833;6109.663968481732;6118.021116621319;6124.5100391968535;7535.434842386843;7542.13265474472;7570.583617901013;7576.507527010782;7595.240475952361;4984.700917944935;4986.82546110888;4991.650584459053;4996.3504744383;5000.62495312853;7675.50109242903;8005;8013.559254241014;8025.9598702006115;8062.596870156469;4864.756762161877;4878.146324393217;4907.386576677915;4908.607311415079;4930.9013819723405;4941.654084214142;4998.875028124326;5020.799168033311;5023.623228257895;5030.773615187325;5994.460498555151;6008.4592386684535;6044.758209671564;6044.818655440344;6053;7589.354904833041;7607.809804754815;7624.771256862303;7629.580373079423;7637.732679356307;1685.0900513722697;1709.376077731619;1717.544850614588;1740.2516917725247;1743.320105158989;914.8581969794692;1051.952662130206;1067.3275836223286;1069.7860427914447;1082.0856376361962;7164.820879478055;7168.247334029956;7186.640667966581;7187.9640601796655;7188.281171882874;6241.34465881085;7121.572705637678;7639.427042971799;7873.094594121635;7906.604669766603;7185.353318201387;7251.7099316027825;7253.093363329584;7276.563406195645;7284.927150728425;4188.434561334242;4218.535961044283;4222.704410691222;4241.215375155544;4241.745495270294;8327.750167494985;8339.20777526134;8343.45767525109;8349.081601023883;8384.748457546284;6588.670566471657;6593;7931;8230.917690823135;8265.876011859827;6930.792076237721;8079.232472915063;8232.053313868862;8280.668773249123;8308.916910830814;7963.761087167394;7968.243016913384;7968.920310796818;8012.919870801217;8025.8394832104195;7469.290417410337;7802.609869506616;7811.648475818488;7819.882701759592;7827.021622297213;8238.958805205935;8305.584720764058;8328.250457458858;8335.041470230759;8370.748877533684;4936.531029552187;4945.381827271591;4946.678465899705;4969.55274025325;4972.403311602631;7911.367090632621;7951.602419879098;7952.6421311039985;7966.681332746625;7993.080795708568;7304.780856574312;7624.8475030500185;7628.771136865903;7674.8081297968;7678.731244406643;6281.466075383643;7567.054118235221;7634.580098094658;7640.044994375703;7655.808604784925;7429.8142546436775;7449.068866391701;7470.887936680845;7504.099508059068;7541.924580754122;7657;7657.57883316423;7666.271704188093;7666.693332266647;7670.846583068419;7250.891236631349;7483.401327893865;7488.8876666848955;7504.812379690333;7526.360259378014 03:56:54 INFO - PROCESS | 1661 | |5;yui.html;725.5574099799123;741.9220981796911;745.8023623739709;762.2796457347802;778.2334400615393;15477.458288959888;15717.056976581413;15722.842771572281;15727.213639318046;15739;14315.642108947282;14632.195229262386;14650.487232946849;14681;14723.558293251193;805.4402190477621;978.6819283732815;981.0385821894565;1005.3063386263455;1006.4967516241879;982.6315131825565;986.6941248213041;988.7923536057436;994.5872462927913;996.5913975270141;995.1093771074884;1028.7376718936698;1063.3194755356578;1063.8989296016905;1065.9680209593726;1048.0567489259727;1049.2864851900704;1054.8945105489465;1056.3556230699267;1058.439027315524;782.7886470652892;792.7661339904741;793.9007624046994;796.080526991326;796.2316364708026;15342.23288835565;15355.4625588105;15356.385744570203;15371;15500.30248638814;13292.86707132926;13339.933300333436;13368.13107148042;13481.865181348257;13500.054996150273;10101.646442374482;10131.408458395586;10304.96950304971;10309.381437113798;10540.9986051325;3612.6206748291575;3625.546806649169;3630.709543236535;3633.2370202257557;3836.7506112102624;82725;84260.15739842584;85146;85942.14057859404;85950;9803.411795292306;9822.361546499455;9943.751406214758;10130.544125514369;10195.439250841127;23329.3001209964;23333.88333058358;23341.649875251886;23360;23437.062517499107;7723.922760772432;7786;7836.725714599962;7873.881891771628;7983.600819958979;3532.2582257725653;4085.754854708727;4171.124063946544;4225.57744225575;4237.406763053175;3741.7193710471824;3759.5676497202626;3782.508273924427;3785.8296376663116;3804.144067584771;683.4225079807554;700.3906601256939;700.394159052416;705.1432509500981;722.552017748999;460.39688008708697;583.5244275915115;585.8125399872085;623.7349126621168;625.8203286804315;8185.672573097128;8225.136360682161;8226.506409615442;8308.376871734645;8476.745697629081;562.9408912064174;591.9200907877467;604.4046614085123;612.2010775937356;631.7062565906875;3285.3100848821537;3778.5654649715084;3859.305325041463;3880;3985.1431942132;89054.55472722594;91687;92480;94860.10279794365;96143.0385696148;73753;75096.62451687707;77010.6149469249;77095.45809083899;77943.61028194823;66062;67273.66363168153;68625.65687171632;71086;71675.6416217926;7535.13345965221;7672.672597669101;7748.845023099394;7817.609119543887;8181.79545511367;49515;49984.75007624939;50578;50634.74682626563;51075.72310692114;64391.814532055265;66223.33776662171;67048.32951670616;67962.32037679758;68337.63324733578;8194.50832950025;8696.695615653422;9947.204223662235;10295.279330447096;10559.472026398496;11933.52265909354;12116.757664846476;12138.211016283913;12186.634400967985;12415.93792031034;11424.371659558805;11661.183717139718;11812.763744724885;11910.940445297718;12184.14710970259;11556.191066625255;11557.122205279495;11691.181617286707;11912.76174476523;12197.268163910194;11280.830787538358;11317.20779545457;11767.88232117668;11775.352355620524;11991.520339186334;8089.393295502743;9709.951450242703;9877.111060004634;9922.355046922215;9939.701808945743;7991.760247192594;8816.294696424398;8824.5587720615;9919.702408927744;9950.253730970351 03:56:54 INFO - PROCESS | 1661 | __end_tp_report 03:56:54 INFO - PROCESS | 1661 | __start_cc_report 03:56:54 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,395 03:56:54 INFO - PROCESS | 1661 | __end_cc_report 03:56:54 INFO - PROCESS | 1661 | __startTimestamp1468753014828__endTimestamp 03:56:54 INFO - PROCESS | 1661 | 03:56:54 INFO - PROCESS | 1661 | ------- Summary: start ------- 03:56:54 INFO - PROCESS | 1661 | Number of tests: 6 03:56:54 INFO - PROCESS | 1661 | 03:56:54 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5971.79 Median:6252.72 stddev:688.80 (11.0%) stddev-sans-first:650.60 03:56:54 INFO - PROCESS | 1661 | Values: 3012.0 3189.6 3470.8 3748.8 3759.3 5961.2 6052.5 6080.7 6123.3 6123.8 5944.6 6067.9 6089.3 6124.3 6136.6 5435.9 5469.7 5477.5 5524.6 5560.5 4662.9 4895.2 4923.9 5409.1 5430.2 5487.3 5495.4 5511.1 5516.6 5517.0 5448.8 5463.4 5492.2 5525.2 5562.1 4248.7 5387.1 5505.3 5506.0 5510.4 5170.9 6062.9 6070.5 6085.5 6130.5 6283.4 6311.2 6359.0 6363.3 6421.9 6430.4 6435.1 6438.3 6441.1 6458.3 5376.8 5996.5 6068.9 6106.6 6205.7 6365.0 6401.3 6461.2 6511.1 6544.2 6321.8 6339.3 6372.8 6382.0 6400.5 6308.0 6442.1 6446.0 6453.2 6460.0 5241.6 6296.2 6330.2 6353.8 6354.4 6321.2 6367.9 6371.3 6387.5 6462.7 5047.3 5066.5 5092.7 5129.9 5193.3 4588.1 4643.0 4651.3 4653.1 4695.4 6168.4 6169.3 6230.9 6234.8 6245.1 6289.9 6381.4 6427.9 6449.3 6455.3 6038.0 6135.5 6228.8 6232.2 6238.1 5668.4 5682.8 5689.2 5692.3 5695.3 6501.2 6618.4 6661.3 6721.6 6732.5 6426.3 6486.3 6491.1 6491.7 6508.0 6668.5 6721.4 6725.9 6746.1 6770.8 6267.9 6292.7 6355.3 6404.1 6412.8 6332.2 6437.1 6453.2 6463.1 6484.4 6327.0 6332.9 6342.5 6344.7 6353.5 6231.8 6235.6 6249.9 6254.6 6346.6 6252.7 6280.7 6292.5 6309.1 6337.6 6218.7 6233.5 6282.3 6308.8 6321.6 6190.2 6286.8 6313.5 6316.9 6330.6 03:56:54 INFO - PROCESS | 1661 | 03:56:54 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:66807.04 Median:38228.90 stddev:147261.90 (385.2%) stddev-sans-first:147590.99 03:56:54 INFO - PROCESS | 1661 | Values: 552.6 557.1 569.0 570.5 582.6 30540.5 39191.0 39310.0 39319.0 39361.8 42885.6 42921.4 43393.3 43395.1 43420.8 9836.3 9897.0 9921.1 9928.5 10038.7 8640.6 9856.5 9942.8 10005.3 10025.5 9880.5 9900.4 9911.3 9929.2 9934.2 8864.2 9637.4 9852.4 9929.4 9961.2 9801.2 9834.0 9887.9 9904.5 9956.3 39359.4 39452.0 39521.6 39977.8 40095.8 3295.6 3420.2 3471.3 3509.7 3525.8 28832.0 29192.0 29244.9 29349.9 29536.1 2828.5 2837.8 2844.9 2878.8 2880.5 480870.0 495487.0 497970.0 499165.0 499956.0 38282.8 62325.0 62565.7 62574.7 62759.0 37099.6 37818.8 38097.6 38175.0 38293.8 14682.2 15122.9 15884.9 15944.9 16329.2 11737.5 13240.9 13467.3 13662.2 13692.8 7183.9 8457.7 8510.7 8573.0 8575.7 3090.5 3441.0 3550.8 3615.1 3640.8 997.8 1068.2 1125.5 1168.4 1171.5 29938.4 30916.5 31051.7 31095.2 31386.0 1044.5 1204.7 1234.2 1235.3 1250.5 13810.1 15108.5 15677.2 15875.4 15901.5 667166.0 669758.0 676925.0 678497.0 680523.0 60168.0 61228.4 62196.7 62361.0 62376.0 1579.3 1585.4 1594.6 1601.2 1629.9 24854.9 25356.6 25789.9 30664.7 31146.7 52441.0 52488.5 52570.2 52881.2 53227.7 453254.0 455196.0 456525.0 457486.7 458496.0 27433.0 27618.0 27904.4 27964.0 43932.6 43749.0 44554.8 44901.0 45096.8 45482.3 36981.3 36994.8 37035.0 37217.9 37266.6 37440.1 37734.8 37815.1 37898.9 38644.0 36040.6 38604.0 39306.4 39800.4 40592.2 32068.7 32236.0 32249.5 32537.5 32701.3 20627.0 24641.3 24786.1 24897.0 24939.5 03:56:54 INFO - PROCESS | 1661 | 03:56:54 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:36089.45 Median:11670.59 stddev:106113.52 (909.2%) stddev-sans-first:106377.26 03:56:54 INFO - PROCESS | 1661 | Values: 350.6 353.3 418.6 423.7 434.4 14432.9 21031.7 21656.4 21717.5 21787.0 20734.8 21110.0 21758.0 22396.0 22440.0 5843.1 6060.4 6178.9 6183.3 6386.1 5598.5 6116.2 6140.9 6166.2 6241.6 5269.7 6156.6 6218.4 6227.0 6234.6 6474.7 6738.0 6804.0 6822.1 6885.4 4610.8 5868.7 6046.1 6089.5 6113.7 13821.2 14249.7 15240.8 22418.0 27697.7 2128.6 2377.2 2433.4 2548.2 2780.4 8199.5 8728.1 9969.4 10198.2 10580.8 1810.9 1828.7 1857.6 1882.5 1888.2 111882.0 123680.0 123974.0 125699.0 126987.0 10058.2 11884.7 11954.2 12178.2 12190.6 12427.9 15248.6 15659.0 15717.1 15725.8 8885.0 9123.4 9151.6 9227.3 9261.5 9014.6 9100.4 9106.3 9200.4 9229.0 4085.0 4698.8 4809.1 4833.7 4856.1 1850.4 1972.1 1980.5 2047.5 2115.2 778.2 954.2 1011.3 1042.9 1070.6 20566.1 20965.8 20988.8 21225.9 21294.6 1028.3 1031.7 1058.6 1065.5 1078.7 9602.3 10790.5 10847.6 11203.6 11224.7 617649.0 619600.0 626645.7 642563.0 682196.0 119447.4 126835.4 127622.0 128287.0 129700.0 1048.6 1054.3 1063.8 1064.9 1072.2 17876.6 18379.9 18421.0 18559.6 18635.4 50431.0 52132.0 52509.9 53792.5 55737.0 68120.0 68375.7 71816.6 72356.6 72871.0 6925.9 8300.9 8519.7 8541.6 8640.6 9392.0 12517.6 13021.6 13369.4 13443.9 11335.7 11666.9 11758.4 11794.4 12124.9 10763.8 11209.8 11435.9 11734.9 11857.9 9937.6 10219.6 11822.9 11939.5 12260.3 8876.5 12079.3 12327.4 12847.3 12978.6 11511.2 12169.7 12330.0 12374.9 12403.1 03:56:54 INFO - PROCESS | 1661 | 03:56:54 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5969.11 Median:4995.43 stddev:3331.21 (66.7%) stddev-sans-first:3323.19 03:56:54 INFO - PROCESS | 1661 | Values: 1442.4 1650.1 1892.7 2029.2 2083.5 8329.6 8979.8 9199.4 9373.4 9389.5 9165.4 9206.2 9268.4 9309.6 9373.3 2026.8 2424.5 2538.7 2538.9 2555.1 2540.2 2544.5 2545.4 2546.2 2591.0 2568.4 2571.6 2591.2 2612.8 2618.2 2449.4 2494.8 2527.6 2563.0 2623.9 2571.8 2591.9 2604.5 2605.9 2608.8 9193.7 9209.9 9452.0 9505.5 9553.3 5624.7 5656.3 5677.7 5690.2 5717.6 3225.0 3590.9 3745.5 3746.1 3757.7 1063.9 1086.1 1087.6 1089.6 1091.4 12423.2 12514.6 12686.9 12697.9 12706.0 4404.4 4970.3 5035.1 5074.3 5091.7 10394.5 10627.8 10632.9 10671.5 10737.4 5535.9 5580.6 5598.9 5719.8 5745.1 4605.8 5026.8 5031.4 5079.4 5187.4 3547.0 3797.9 4191.4 4280.6 4369.1 1255.6 1369.9 1407.1 1429.1 1431.2 857.9 933.3 1006.2 1006.2 1010.1 6007.4 6039.9 6199.7 6240.7 6256.3 989.8 1000.1 1031.3 1040.3 1068.5 3947.3 4123.8 4149.5 4196.8 4371.3 11042.1 11080.5 11097.1 11246.2 11589.2 10755.2 10887.7 11166.6 11305.6 11307.6 8286.7 8458.0 8620.6 8680.5 8718.4 5093.2 5270.9 5474.3 6079.8 6177.6 9844.4 9971.6 9988.4 10007.3 10175.8 9293.7 10008.7 10276.6 10293.4 10420.2 7140.8 7359.7 7679.9 7770.1 7862.0 8734.2 8757.4 8767.8 8812.0 8829.5 7414.0 7713.4 7737.6 7842.6 7863.7 7437.9 7657.0 7670.2 7670.3 7757.7 7331.9 7342.5 7371.9 7378.9 7379.0 5020.5 5056.0 5101.5 5226.8 5227.6 5041.1 5085.3 5130.5 5152.0 5250.9 03:56:54 INFO - PROCESS | 1661 | 03:56:54 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:6195.55 Median:7098.20 stddev:2004.45 (28.2%) stddev-sans-first:1961.27 03:56:54 INFO - PROCESS | 1661 | Values: 338.5 339.7 341.6 347.3 351.1 5703.7 7090.6 7105.8 7150.1 7243.6 5297.5 7004.7 7033.9 7050.9 7060.0 4980.7 5079.2 5198.2 5204.5 5219.1 5137.8 5191.9 5214.3 5223.0 5242.8 5180.8 5212.4 5214.3 5219.4 5220.4 5191.6 5223.7 5229.5 5235.6 5269.8 3660.3 4491.3 4492.0 4498.6 4499.1 6615.7 6768.4 6807.8 6835.8 6887.0 6068.1 6108.6 6109.7 6118.0 6124.5 7535.4 7542.1 7570.6 7576.5 7595.2 4984.7 4986.8 4991.7 4996.4 5000.6 7675.5 8005.0 8013.6 8026.0 8062.6 4864.8 4878.1 4907.4 4908.6 4930.9 4941.7 4998.9 5020.8 5023.6 5030.8 5994.5 6008.5 6044.8 6044.8 6053.0 7589.4 7607.8 7624.8 7629.6 7637.7 1685.1 1709.4 1717.5 1740.3 1743.3 914.9 1052.0 1067.3 1069.8 1082.1 7164.8 7168.2 7186.6 7188.0 7188.3 6241.3 7121.6 7639.4 7873.1 7906.6 7185.4 7251.7 7253.1 7276.6 7284.9 4188.4 4218.5 4222.7 4241.2 4241.7 8327.8 8339.2 8343.5 8349.1 8384.7 6588.7 6593.0 7931.0 8230.9 8265.9 6930.8 8079.2 8232.1 8280.7 8308.9 7963.8 7968.2 7968.9 8012.9 8025.8 7469.3 7802.6 7811.6 7819.9 7827.0 8239.0 8305.6 8328.3 8335.0 8370.7 4936.5 4945.4 4946.7 4969.6 4972.4 7911.4 7951.6 7952.6 7966.7 7993.1 7304.8 7624.8 7628.8 7674.8 7678.7 6281.5 7567.1 7634.6 7640.0 7655.8 7429.8 7449.1 7470.9 7504.1 7541.9 7657.0 7657.6 7666.3 7666.7 7670.8 7250.9 7483.4 7488.9 7504.8 7526.4 03:56:54 INFO - PROCESS | 1661 | 03:56:54 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:18465.14 Median:25819.33 stddev:25793.24 (99.9%) stddev-sans-first:25831.20 03:56:54 INFO - PROCESS | 1661 | Values: 725.6 741.9 745.8 762.3 778.2 15477.5 15717.1 15722.8 15727.2 15739.0 14315.6 14632.2 14650.5 14681.0 14723.6 805.4 978.7 981.0 1005.3 1006.5 982.6 986.7 988.8 994.6 996.6 995.1 1028.7 1063.3 1063.9 1066.0 1048.1 1049.3 1054.9 1056.4 1058.4 782.8 792.8 793.9 796.1 796.2 15342.2 15355.5 15356.4 15371.0 15500.3 13292.9 13339.9 13368.1 13481.9 13500.1 10101.6 10131.4 10305.0 10309.4 10541.0 3612.6 3625.5 3630.7 3633.2 3836.8 82725.0 84260.2 85146.0 85942.1 85950.0 9803.4 9822.4 9943.8 10130.5 10195.4 23329.3 23333.9 23341.6 23360.0 23437.1 7723.9 7786.0 7836.7 7873.9 7983.6 3532.3 4085.8 4171.1 4225.6 4237.4 3741.7 3759.6 3782.5 3785.8 3804.1 683.4 700.4 700.4 705.1 722.6 460.4 583.5 585.8 623.7 625.8 8185.7 8225.1 8226.5 8308.4 8476.7 562.9 591.9 604.4 612.2 631.7 3285.3 3778.6 3859.3 3880.0 3985.1 89054.6 91687.0 92480.0 94860.1 96143.0 73753.0 75096.6 77010.6 77095.5 77943.6 66062.0 67273.7 68625.7 71086.0 71675.6 7535.1 7672.7 7748.8 7817.6 8181.8 49515.0 49984.8 50578.0 50634.7 51075.7 64391.8 66223.3 67048.3 67962.3 68337.6 8194.5 8696.7 9947.2 10295.3 10559.5 11933.5 12116.8 12138.2 12186.6 12415.9 11424.4 11661.2 11812.8 11910.9 12184.1 11556.2 11557.1 11691.2 11912.8 12197.3 11280.8 11317.2 11767.9 11775.4 11991.5 8089.4 9710.0 9877.1 9922.4 9939.7 7991.8 8816.3 8824.6 9919.7 9950.3 03:56:54 INFO - PROCESS | 1661 | -------- Summary: end -------- 03:56:54 INFO - PROCESS | 1661 | 03:56:55 INFO - TEST-INFO | 1661: exit 0 03:56:55 INFO - TEST-OK | dromaeo_css | took 1109927ms 03:56:55 INFO - TEST-START | kraken 03:56:55 INFO - operating with platform_type : mac_ 03:56:55 INFO - Initialising browser for kraken test... 03:56:55 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpCoqm7Q/profile http://localhost:49283/getInfo.html) 03:56:57 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 03:56:57 INFO - PROCESS | 1677 | colorDepth:24 03:56:57 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 03:56:57 INFO - PROCESS | 1677 | __metrics 03:56:58 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 03:56:58 INFO - TEST-INFO | 1677: exit 0 03:56:58 INFO - Browser initialized. 03:56:58 INFO - Running cycle 1/1 for kraken test... 03:56:58 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpCoqm7Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:57:10 INFO - PROCESS | 1681 | RSS: Main: 170967040 03:57:10 INFO - PROCESS | 1681 | 03:57:13 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection) 03:57:14 INFO - PROCESS | 1681 | RSS: Main: 183681024 03:57:14 INFO - PROCESS | 1681 | 03:57:17 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-dft) 03:57:17 INFO - PROCESS | 1681 | RSS: Main: 192495616 03:57:17 INFO - PROCESS | 1681 | 03:57:21 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-fft) 03:57:21 INFO - PROCESS | 1681 | RSS: Main: 196177920 03:57:21 INFO - PROCESS | 1681 | 03:57:23 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator) 03:57:24 INFO - PROCESS | 1681 | RSS: Main: 196341760 03:57:24 INFO - PROCESS | 1681 | 03:57:26 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:57:27 INFO - PROCESS | 1681 | RSS: Main: 197804032 03:57:27 INFO - PROCESS | 1681 | 03:57:29 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom) 03:57:30 INFO - PROCESS | 1681 | RSS: Main: 198029312 03:57:30 INFO - PROCESS | 1681 | 03:57:33 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate) 03:57:34 INFO - PROCESS | 1681 | RSS: Main: 198078464 03:57:34 INFO - PROCESS | 1681 | 03:57:37 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial) 03:57:37 INFO - PROCESS | 1681 | RSS: Main: 198238208 03:57:37 INFO - PROCESS | 1681 | 03:57:39 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:57:39 INFO - PROCESS | 1681 | RSS: Main: 197730304 03:57:39 INFO - PROCESS | 1681 | 03:57:41 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:57:42 INFO - PROCESS | 1681 | RSS: Main: 197914624 03:57:42 INFO - PROCESS | 1681 | 03:57:44 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:57:45 INFO - PROCESS | 1681 | RSS: Main: 198160384 03:57:45 INFO - PROCESS | 1681 | 03:57:47 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:57:48 INFO - PROCESS | 1681 | RSS: Main: 198365184 03:57:48 INFO - PROCESS | 1681 | 03:57:51 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:57:52 INFO - PROCESS | 1681 | RSS: Main: 198336512 03:57:52 INFO - PROCESS | 1681 | 03:57:53 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49283/tests/kraken/driver.html?testName=ai-astar) 03:57:53 INFO - PROCESS | 1681 | RSS: Main: 200269824 03:57:53 INFO - PROCESS | 1681 | 03:57:53 INFO - PROCESS | 1681 | __start_tp_report 03:57:53 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 03:57:53 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 03:57:53 INFO - PROCESS | 1681 | |i|pagename|runs| 03:57:53 INFO - PROCESS | 1681 | |0;ai-astar;117;112;117;117;116;133;116;121;166;169 03:57:53 INFO - PROCESS | 1681 | |1;audio-beat-detection;153;151;129;174;109;177;166;152;179;120 03:57:53 INFO - PROCESS | 1681 | |2;audio-dft;194;196;201;193;195;200;200;274;274;205 03:57:53 INFO - PROCESS | 1681 | |3;audio-fft;65;78;73;141;55;56;60;67;128;55 03:57:53 INFO - PROCESS | 1681 | |4;audio-oscillator;77;83;130;103;114;116;128;79;77;119 03:57:53 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;88;86;87;89;90;74;137;132;92;86 03:57:53 INFO - PROCESS | 1681 | |6;imaging-darkroom;94;117;94;94;92;131;108;105;150;134 03:57:53 INFO - PROCESS | 1681 | |7;imaging-desaturate;90;92;98;92;93;90;126;150;105;136 03:57:53 INFO - PROCESS | 1681 | |8;json-parse-financial;56;63;64;64;65;65;63;79;62;59 03:57:53 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;63;67;66;62;66;68;69;66;64;66 03:57:53 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;80;80;81;80;79;82;80;81;86;82 03:57:53 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;127;124;127;123;125;133;137;140;162;139 03:57:53 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;214;150;221;221;210;166;167;171;175;177 03:57:53 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;69;66;70;66;68;68;66;67;68;67 03:57:53 INFO - PROCESS | 1681 | __end_tp_report 03:57:53 INFO - PROCESS | 1681 | __start_cc_report 03:57:53 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,1036 03:57:53 INFO - PROCESS | 1681 | __end_cc_report 03:57:53 INFO - PROCESS | 1681 | __startTimestamp1468753073907__endTimestamp 03:57:53 INFO - PROCESS | 1681 | 03:57:53 INFO - PROCESS | 1681 | ------- Summary: start ------- 03:57:53 INFO - PROCESS | 1681 | Number of tests: 14 03:57:53 INFO - PROCESS | 1681 | 03:57:53 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:128.40 Median:119.00 stddev:21.36 (17.9%) stddev-sans-first:22.25 03:57:53 INFO - PROCESS | 1681 | Values: 117.0 112.0 117.0 117.0 116.0 133.0 116.0 121.0 166.0 169.0 03:57:53 INFO - PROCESS | 1681 | 03:57:53 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:151.00 Median:159.50 stddev:24.56 (15.4%) stddev-sans-first:26.04 03:57:53 INFO - PROCESS | 1681 | Values: 153.0 151.0 129.0 174.0 109.0 177.0 166.0 152.0 179.0 120.0 03:57:53 INFO - PROCESS | 1681 | 03:57:53 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:213.20 Median:200.50 stddev:32.25 (16.1%) stddev-sans-first:33.45 03:57:53 INFO - PROCESS | 1681 | Values: 194.0 196.0 201.0 193.0 195.0 200.0 200.0 274.0 274.0 205.0 03:57:53 INFO - PROCESS | 1681 | 03:57:53 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:77.80 Median:62.50 stddev:31.00 (49.6%) stddev-sans-first:32.53 03:57:53 INFO - PROCESS | 1681 | Values: 65.0 78.0 73.0 141.0 55.0 56.0 60.0 67.0 128.0 55.0 03:57:53 INFO - PROCESS | 1681 | 03:57:53 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:102.60 Median:103.50 stddev:21.67 (20.9%) stddev-sans-first:20.91 03:57:53 INFO - PROCESS | 1681 | Values: 77.0 83.0 130.0 103.0 114.0 116.0 128.0 79.0 77.0 119.0 03:57:53 INFO - PROCESS | 1681 | 03:57:53 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:96.10 Median:87.50 stddev:20.84 (23.8%) stddev-sans-first:21.89 03:57:53 INFO - PROCESS | 1681 | Values: 88.0 86.0 87.0 89.0 90.0 74.0 137.0 132.0 92.0 86.0 03:57:53 INFO - PROCESS | 1681 | 03:57:53 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:111.90 Median:121.00 stddev:20.41 (16.9%) stddev-sans-first:20.60 03:57:53 INFO - PROCESS | 1681 | Values: 94.0 117.0 94.0 94.0 92.0 131.0 108.0 105.0 150.0 134.0 03:57:53 INFO - PROCESS | 1681 | 03:57:53 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:107.20 Median:91.00 stddev:22.01 (24.2%) stddev-sans-first:22.45 03:57:53 INFO - PROCESS | 1681 | Values: 90.0 92.0 98.0 92.0 93.0 90.0 126.0 150.0 105.0 136.0 03:57:53 INFO - PROCESS | 1681 | 03:57:53 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:64.00 stddev:5.98 (9.3%) stddev-sans-first:5.60 03:57:53 INFO - PROCESS | 1681 | Values: 56.0 63.0 64.0 64.0 65.0 65.0 63.0 79.0 62.0 59.0 03:57:53 INFO - PROCESS | 1681 | 03:57:53 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:2.16 (3.3%) stddev-sans-first:2.06 03:57:53 INFO - PROCESS | 1681 | Values: 63.0 67.0 66.0 62.0 66.0 68.0 69.0 66.0 64.0 66.0 03:57:53 INFO - PROCESS | 1681 | 03:57:53 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:81.00 stddev:1.97 (2.4%) stddev-sans-first:2.05 03:57:53 INFO - PROCESS | 1681 | Values: 80.0 80.0 81.0 80.0 79.0 82.0 80.0 81.0 86.0 82.0 03:57:53 INFO - PROCESS | 1681 | 03:57:53 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:133.70 Median:135.00 stddev:11.80 (8.7%) stddev-sans-first:12.27 03:57:53 INFO - PROCESS | 1681 | Values: 127.0 124.0 127.0 123.0 125.0 133.0 137.0 140.0 162.0 139.0 03:57:53 INFO - PROCESS | 1681 | 03:57:53 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.20 Median:193.50 stddev:26.41 (13.7%) stddev-sans-first:26.18 03:57:53 INFO - PROCESS | 1681 | Values: 214.0 150.0 221.0 221.0 210.0 166.0 167.0 171.0 175.0 177.0 03:57:53 INFO - PROCESS | 1681 | 03:57:53 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:1.35 (2.0%) stddev-sans-first:1.32 03:57:53 INFO - PROCESS | 1681 | Values: 69.0 66.0 70.0 66.0 68.0 68.0 66.0 67.0 68.0 67.0 03:57:53 INFO - PROCESS | 1681 | -------- Summary: end -------- 03:57:53 INFO - PROCESS | 1681 | 03:57:54 INFO - TEST-INFO | 1681: exit 0 03:57:54 INFO - TEST-OK | kraken | took 59188ms 03:57:54 INFO - SUITE-END | took 1169s 03:57:55 INFO - Completed test suite (00:19:29) 03:57:55 INFO - javascript benchmark 03:57:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3012.0361484325026, 3189.6012998375204, 3470.7570470067108, 3748.831302591384, 3759.3045286621978, 5961.195238642787, 6052.455279024887, 6080.72636731347, 6123.295820980588, 6123.846219197111, 5944.583879128452, 6067.9393206067925, 6089.29973053099, 6124.295705993812, 6136.631802091878, 5435.918461223085, 5469.699166545838, 5477.5070243678065, 5524.613277070606, 5560.499555040045, 4662.90674186516, 4895.192293271606, 4923.926141107886, 5409.134538473845, 5430.239766432703, 5487.341519017704, 5495.395506494282, 5511.090670039459, 5516.558675305986, 5517.0, 5448.8365349039195, 5463.399026107125, 5492.176173573976, 5525.2264682944015, 5562.110062390038, 4248.681348898814, 5387.057264978613, 5505.339359276913, 5505.981393442186, 5510.448955104497, 5170.896582068375, 6062.8787424251705, 6070.544709146831, 6085.513158947274, 6130.478909292716, 6283.371662833726, 6311.179546658951, 6359.046143078529, 6363.300036995926, 6421.903671444932, 6430.421262086388, 6435.131257280254, 6438.291787903374, 6441.130447389589, 6458.321876202978, 5376.843978544593, 5996.5202783777195, 6068.878622427526, 6106.603070800384, 6205.6897155142515, 6364.968175159094, 6401.263854656681, 6461.160049193669, 6511.088447617338, 6544.247411547638, 6321.810345689637, 6339.334369891188, 6372.84067898297, 6381.968090159519, 6400.455961243253, 6307.990721484541, 6442.130312407859, 6446.033095035782, 6453.193350831146, 6459.967700161469, 5241.633085684041, 6296.212973378328, 6330.177076980055, 6353.777617783356, 6354.3963323484195, 6321.1782468278825, 6367.872642547123, 6371.299157092716, 6387.489000879918, 6462.741490340334, 5047.343845300087, 5066.493350664904, 5092.668976516515, 5129.9230511542355, 5193.272941788153, 4588.128255631419, 4643.0017546227045, 4651.348811166439, 4653.139169253699, 4695.436547614308, 6168.414000669929, 6169.321374648785, 6230.875382492415, 6234.815385076821, 6245.125682404467, 6289.937100629027, 6381.361863813581, 6427.871442571122, 6449.290578036413, 6455.321041767614, 6037.969810150921, 6135.477655820289, 6228.813135605939, 6232.18981532407, 6238.095476155932, 5668.404817494186, 5682.801101961411, 5689.203511508392, 5692.288463942007, 5695.316562012585, 6501.2198536175965, 6618.437432818168, 6661.300563440769, 6721.563098398588, 6732.4950628703045, 6426.260979987362, 6486.254080780582, 6491.1236983008575, 6491.7403303869205, 6508.023796430573, 6668.499862510234, 6721.428678562182, 6725.865482690417, 6746.055552222595, 6770.796876093725, 6267.905981410374, 6292.653904035322, 6355.332690067661, 6404.135441715495, 6412.775552855627, 6332.176817013758, 6437.098806167047, 6453.225612926479, 6463.095166676575, 6484.416402523795, 6326.968365158329, 6332.936670633235, 6342.460890824332, 6344.714487847966, 6353.491720662243, 6231.844203894939, 6235.625862448268, 6249.937500624936, 6254.6247225166635, 6346.587471814318, 6252.718627661675, 6280.748770049329, 6292.52806039558, 6309.085182648399, 6337.556371054166, 6218.720157593009, 6233.532485063548, 6282.277538083179, 6308.810735677937, 6321.589096708699, 6190.226221722285, 6286.842828929313, 6313.526485513697, 6316.873662526632, 6330.588511746721], "value": 5926.908665623028, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.5689961829773, 557.1197507937459, 568.9978502492795, 570.4580648384031, 582.554345925367, 30540.541891871613, 39191.0, 39310.0, 39319.0, 39361.80319098401, 42885.57114428855, 42921.356179657254, 43393.34909976353, 43395.13209735811, 43420.782896085555, 9836.262280328987, 9897.0, 9921.107100360967, 9928.503574821267, 10038.749031274203, 8640.567971601395, 9856.507174641276, 9942.801143977116, 10005.349652272615, 10025.548850301717, 9880.456574888378, 9900.356476828983, 9911.256655750847, 9929.156021738161, 9934.205263578933, 8864.246539044188, 9637.37357071788, 9852.408855468659, 9929.404235745878, 9961.153301969342, 9801.166900813369, 9833.950830245803, 9887.901120988841, 9904.455254960974, 9956.303058785888, 39359.40960885589, 39452.0, 39521.60478395208, 39977.80011099955, 40095.7995210025, 3295.555100061509, 3420.1791570023265, 3471.288385880888, 3509.736769742254, 3525.7708248963736, 28832.0, 29192.0, 29244.853775731197, 29349.853250733824, 29536.113916582537, 2828.5474324108045, 2837.7729781617422, 2844.871980760871, 2878.827270363785, 2880.510313246753, 480870.0, 495487.0, 497970.0, 499165.0, 499956.0, 38282.80858595689, 62325.0, 62565.68717156385, 62574.68712656407, 62759.0, 37099.62900370962, 37818.81090594529, 38097.61902380996, 38175.0, 38293.80853095717, 14682.192479413734, 15122.924385378003, 15884.88805783584, 15944.920275398548, 16329.183540823149, 11737.530498780125, 13240.933795331155, 13467.259300738553, 13662.18026918388, 13692.794608080887, 7183.92816071843, 8457.746267611981, 8510.65957361711, 8572.957135214285, 8575.699850505202, 3090.5055191169304, 3440.9505100944452, 3550.751447398657, 3615.1142969972525, 3640.7997560133917, 997.8253805583994, 1068.1775033224374, 1125.5160281079052, 1168.3807581981564, 1171.5313874450287, 29938.40123197524, 30916.53625195624, 31051.68948310488, 31095.222619434695, 31386.0, 1044.4777611194402, 1204.6506513111274, 1234.216272666863, 1235.320573684488, 1250.474800583757, 13810.135631689795, 15108.471203507825, 15677.216139193222, 15875.364985400454, 15901.52295431139, 667166.0, 669758.0, 676925.0, 678497.0, 680523.0, 60168.0, 61228.38771612316, 62196.68901655463, 62361.0, 62376.0, 1579.3287852662572, 1585.4371698047257, 1594.6172918499476, 1601.1513897634272, 1629.9266533006044, 24854.875725621256, 25356.619650704884, 25789.87105064538, 30664.693353067076, 31146.68853311438, 52441.0, 52488.47511524836, 52570.21144682756, 52881.20678189907, 53227.66930826761, 453254.0, 455196.0, 456525.0, 457486.7125664484, 458496.0, 27433.039843605377, 27618.0, 27904.44191116207, 27964.021259255827, 43932.560674392844, 43749.0, 44554.77722611366, 44901.0, 45096.77451612721, 45482.31776523421, 36981.260374792895, 36994.815025925775, 37035.0, 37217.88346349614, 37266.62733372632, 37440.06399839917, 37734.81132594319, 37815.05462363463, 37898.86303410902, 38644.0, 36040.63959360373, 38604.03489912662, 39306.410403843394, 39800.40299395569, 40592.1881562373, 32068.679313207504, 32236.0, 32249.51625725569, 32537.511937320487, 32701.258317543346, 20627.0, 24641.260762177164, 24786.132485362927, 24897.004119835005, 24939.50362978227], "value": 18947.301522295787, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.5793048341989, 353.33220213795914, 418.61905665844085, 423.7309308589047, 434.3593200029956, 14432.855671443282, 21031.68452473212, 21656.350309490703, 21717.457063573398, 21787.019584118712, 20734.792652073476, 21110.0, 21758.0, 22395.99218035188, 22440.0, 5843.123531470271, 6060.393960603938, 6178.907316390258, 6183.319834818167, 6386.10594516768, 5598.4961353478175, 6116.235470566179, 6140.938590614103, 6166.198394208769, 6241.625502469844, 5269.710165940872, 6156.56904016719, 6218.378162183768, 6226.968865155668, 6234.594751341171, 6474.74101035958, 6737.966310168466, 6803.965980170067, 6822.147231596051, 6885.414739747128, 4610.76946152691, 5868.677222752747, 6046.123312119762, 6089.543284253655, 6113.694315284218, 13821.239831809347, 14249.71500569993, 15240.847591524054, 22418.0, 27697.723022769715, 2128.5849259394427, 2377.239283429303, 2433.4038160650753, 2548.210054882992, 2780.4439112177647, 8199.508029518189, 8728.083551227157, 9969.35199212049, 10198.16433042052, 10580.841287380696, 1810.900400477973, 1828.6616975859376, 1857.5820440400937, 1882.5387779993853, 1888.169205549554, 111882.0, 123680.0, 123974.0, 125699.0, 126986.99999999908, 10058.245631577662, 11884.702882427835, 11954.16320857549, 12178.208416452902, 12190.57332993341, 12427.93786031064, 15248.618784530254, 15658.982166159163, 15717.056976581212, 15725.842741572667, 8884.955575222211, 9123.406978546373, 9151.588178531982, 9227.26181905446, 9261.49061801594, 9014.602736575842, 9100.408473449337, 9106.31702622306, 9200.377892300796, 9229.0, 4084.959150408458, 4698.778317637439, 4809.110314591811, 4833.73414462208, 4856.1016212000895, 1850.4171186076344, 1972.0534143611146, 1980.5345743750206, 2047.4950911080343, 2115.249086574244, 778.2334400615389, 954.2127744610585, 1011.3173607814696, 1042.9018670027244, 1070.5610699613278, 20566.07452664634, 20965.79034209669, 20988.790112098988, 21225.938703064785, 21294.574108517743, 1028.2699283508794, 1031.6956497833157, 1058.5659879449543, 1065.4779158212575, 1078.719532921446, 9602.279829012852, 10790.460476976119, 10847.566097356017, 11203.60787372438, 11224.66326010221, 617649.0, 619600.0, 626645.7335426678, 642563.0, 682196.0, 119447.40276298736, 126835.36582317029, 127622.0, 128287.0, 129700.0, 1048.6172547020283, 1054.267284237463, 1063.7712891728315, 1064.8988346107108, 1072.1958531101673, 17876.55308617295, 18379.90810045941, 18421.0, 18559.628807424047, 18635.44093677192, 50431.0, 52132.0, 52509.94980100453, 53792.462075378746, 55737.0, 68120.0, 68375.65812170907, 71816.64091679717, 72356.63821681068, 72871.0, 6925.896111558432, 8300.87548686758, 8519.74440766778, 8541.615627296662, 8640.611172497378, 9391.953040234755, 12517.624471265877, 13021.609351719464, 13369.398377072861, 13443.932780336036, 11335.716607084889, 11666.88333116658, 11758.3532905691, 11794.351310677996, 12124.93937530342, 10763.838542421714, 11209.831852522382, 11435.885641143816, 11734.941325293661, 11857.940710296394, 9937.552810123707, 10219.620351252486, 11822.940885295518, 11939.52241910314, 12260.26438413698, 8876.511791851251, 12079.335636540074, 12327.383630818602, 12847.29339886278, 12978.610641680765, 11511.19421640477, 12169.69575760613, 12330.0, 12374.876251237372, 12403.131780775258], "value": 9857.381361494003, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1442.3653592419334, 1650.1254335202339, 1892.6593213221615, 2029.168041103147, 2083.5312054787664, 8329.58352082395, 8979.775505612362, 9199.402038867469, 9373.390729602579, 9389.483578403186, 9165.404248723828, 9206.217471514929, 9268.397554158975, 9309.581068851901, 9373.297002724788, 2026.8378529717588, 2424.4787370715294, 2538.657281267024, 2538.923832285034, 2555.0801711383933, 2540.187140115164, 2544.5419824431597, 2545.4272788622598, 2546.1597672767944, 2591.0, 2568.3579105223694, 2571.6014017827265, 2591.1578736910487, 2612.8040396970305, 2618.2145356393103, 2449.448874003353, 2494.762997515233, 2527.5576774064557, 2563.0132399026324, 2623.8819253133656, 2571.7942564594973, 2591.896324147032, 2604.4530648563823, 2605.882735276917, 2608.7782538484253, 9193.678221262291, 9209.861852072225, 9452.0, 9505.524723763785, 9553.331266811327, 5624.718764061821, 5656.321241451053, 5677.659340439587, 5690.231818704463, 5717.599768016241, 3225.048610659861, 3590.874319398835, 3745.5130832991535, 3746.063484128968, 3757.6805971492454, 1063.8936106389376, 1086.1202426034938, 1087.6410784441196, 1089.5696200000955, 1091.3670071358592, 12423.192492487959, 12514.624561263177, 12686.936565317206, 12697.93651031739, 12706.04704647155, 4404.361367601679, 4970.3290055842, 5035.1188542005, 5074.314967479417, 5091.66904151229, 10394.48027598617, 10627.84058239127, 10632.946835265775, 10671.466426678635, 10737.409442480739, 5535.916961245584, 5580.581456390787, 5598.944010559923, 5719.7712091516705, 5745.13822926556, 4605.838795642137, 5026.773795179226, 5031.396232452129, 5079.390473143172, 5187.429382767892, 3546.964530354715, 3797.9430308545393, 4191.350340697197, 4280.571942805756, 4369.104333611602, 1255.604484587352, 1369.8904087672963, 1407.0853944935668, 1429.0639631041684, 1431.1556181852757, 857.9270761985301, 933.2906990687127, 1006.2000709436016, 1006.2050979725934, 1010.1413798271558, 6007.36922623127, 6039.8792024159275, 6199.721012554446, 6240.719167637377, 6256.311805701369, 989.8267803134422, 1000.1498726082772, 1031.3090229546222, 1040.266612038511, 1068.4817863336275, 3947.2697550953167, 4123.773192474383, 4149.460570125864, 4196.769177695256, 4371.256886329332, 11042.116630669527, 11080.501377438035, 11097.056750176325, 11246.212765106528, 11589.188756787113, 10755.193360497993, 10887.727806804893, 11166.609168679057, 11305.604303849326, 11307.604233851776, 8286.668533258722, 8457.957710211409, 8620.568971551398, 8680.522571258642, 8718.43330183536, 5093.23601459784, 5270.919461510458, 5474.288342515448, 6079.787207447718, 6177.62934223948, 9844.409335439897, 9971.551280192263, 9988.350757200758, 10007.34952228132, 10175.796484070424, 9293.72118836436, 10008.699739007841, 10276.588936442458, 10293.43386113771, 10420.166386689198, 7140.7857764267155, 7359.705611775683, 7679.884801727867, 7770.145284018867, 7862.0, 8734.213920747163, 8757.430767000125, 8767.824643507221, 8812.0, 8829.514376709343, 7414.0, 7713.382929365525, 7737.613119344122, 7842.64708088126, 7863.724769633035, 7437.851242975218, 7657.042869641295, 7670.194629563815, 7670.27132422419, 7757.72847950319, 7331.890021649786, 7342.5227360221415, 7371.889421658787, 7378.852422951618, 7379.0, 5020.523050310215, 5055.974720126376, 5101.464346243591, 5226.843194704013, 5227.581793456438, 5041.117804384291, 5085.262636917701, 5130.538251557377, 5152.02111598795, 5250.868728281824], "value": 4822.626084907831, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.5024014698393, 339.66373290442453, 341.57473944938556, 347.3296537682273, 351.0574108518629, 5703.686297253649, 7090.610016449094, 7105.786826395203, 7150.141982962039, 7243.6378181090995, 5297.523222909937, 7004.719811207546, 7033.8944915826305, 7050.85898282034, 7060.046893669366, 4980.651354405193, 5079.187330027178, 5198.246254293125, 5204.479552044784, 5219.138842091051, 5137.794488220486, 5191.948080519203, 5214.348206474191, 5223.0, 5242.842714718565, 5180.844574662748, 5212.4266330703595, 5214.270002199695, 5219.373675158968, 5220.373555173384, 5191.610629202806, 5223.71269580173, 5229.529342359187, 5235.55497782689, 5269.762860671241, 3660.2862441823854, 4491.30384790358, 4491.955080449186, 4498.572635599613, 4499.1451624191295, 6615.669216539154, 6768.357006084463, 6807.829804254884, 6835.7949261522235, 6887.0, 6068.059450785136, 6108.602940808833, 6109.663968481732, 6118.021116621319, 6124.5100391968535, 7535.434842386843, 7542.13265474472, 7570.583617901013, 7576.507527010782, 7595.240475952361, 4984.700917944935, 4986.82546110888, 4991.650584459053, 4996.3504744383, 5000.62495312853, 7675.50109242903, 8005.0, 8013.559254241014, 8025.9598702006115, 8062.596870156469, 4864.756762161877, 4878.146324393217, 4907.386576677915, 4908.607311415079, 4930.9013819723405, 4941.654084214142, 4998.875028124326, 5020.799168033311, 5023.623228257895, 5030.773615187325, 5994.460498555151, 6008.4592386684535, 6044.758209671564, 6044.818655440344, 6053.0, 7589.354904833041, 7607.809804754815, 7624.771256862303, 7629.580373079423, 7637.732679356307, 1685.0900513722697, 1709.376077731619, 1717.544850614588, 1740.2516917725247, 1743.320105158989, 914.8581969794692, 1051.952662130206, 1067.3275836223286, 1069.7860427914447, 1082.0856376361962, 7164.820879478055, 7168.247334029956, 7186.640667966581, 7187.9640601796655, 7188.281171882874, 6241.34465881085, 7121.572705637678, 7639.427042971799, 7873.094594121635, 7906.604669766603, 7185.353318201387, 7251.7099316027825, 7253.093363329584, 7276.563406195645, 7284.927150728425, 4188.434561334242, 4218.535961044283, 4222.704410691222, 4241.215375155544, 4241.745495270294, 8327.750167494985, 8339.20777526134, 8343.45767525109, 8349.081601023883, 8384.748457546284, 6588.670566471657, 6593.0, 7931.0, 8230.917690823135, 8265.876011859827, 6930.792076237721, 8079.232472915063, 8232.053313868862, 8280.668773249123, 8308.916910830814, 7963.761087167394, 7968.243016913384, 7968.920310796818, 8012.919870801217, 8025.8394832104195, 7469.290417410337, 7802.609869506616, 7811.648475818488, 7819.882701759592, 7827.021622297213, 8238.958805205935, 8305.584720764058, 8328.250457458858, 8335.041470230759, 8370.748877533684, 4936.531029552187, 4945.381827271591, 4946.678465899705, 4969.55274025325, 4972.403311602631, 7911.367090632621, 7951.602419879098, 7952.6421311039985, 7966.681332746625, 7993.080795708568, 7304.780856574312, 7624.8475030500185, 7628.771136865903, 7674.8081297968, 7678.731244406643, 6281.466075383643, 7567.054118235221, 7634.580098094658, 7640.044994375703, 7655.808604784925, 7429.8142546436775, 7449.068866391701, 7470.887936680845, 7504.099508059068, 7541.924580754122, 7657.0, 7657.57883316423, 7666.271704188093, 7666.693332266647, 7670.846583068419, 7250.891236631349, 7483.401327893865, 7488.8876666848955, 7504.812379690333, 7526.360259378014], "value": 5511.86725772628, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.5574099799123, 741.9220981796911, 745.8023623739709, 762.2796457347802, 778.2334400615393, 15477.458288959888, 15717.056976581413, 15722.842771572281, 15727.213639318046, 15739.0, 14315.642108947282, 14632.195229262386, 14650.487232946849, 14681.0, 14723.558293251193, 805.4402190477621, 978.6819283732815, 981.0385821894565, 1005.3063386263455, 1006.4967516241879, 982.6315131825565, 986.6941248213041, 988.7923536057436, 994.5872462927913, 996.5913975270141, 995.1093771074884, 1028.7376718936698, 1063.3194755356578, 1063.8989296016905, 1065.9680209593726, 1048.0567489259727, 1049.2864851900704, 1054.8945105489465, 1056.3556230699267, 1058.439027315524, 782.7886470652892, 792.7661339904741, 793.9007624046994, 796.080526991326, 796.2316364708026, 15342.23288835565, 15355.4625588105, 15356.385744570203, 15371.0, 15500.30248638814, 13292.86707132926, 13339.933300333436, 13368.13107148042, 13481.865181348257, 13500.054996150273, 10101.646442374482, 10131.408458395586, 10304.96950304971, 10309.381437113798, 10540.9986051325, 3612.6206748291575, 3625.546806649169, 3630.709543236535, 3633.2370202257557, 3836.7506112102624, 82725.0, 84260.15739842584, 85146.0, 85942.14057859404, 85950.0, 9803.411795292306, 9822.361546499455, 9943.751406214758, 10130.544125514369, 10195.439250841127, 23329.3001209964, 23333.88333058358, 23341.649875251886, 23360.0, 23437.062517499107, 7723.922760772432, 7786.0, 7836.725714599962, 7873.881891771628, 7983.600819958979, 3532.2582257725653, 4085.754854708727, 4171.124063946544, 4225.57744225575, 4237.406763053175, 3741.7193710471824, 3759.5676497202626, 3782.508273924427, 3785.8296376663116, 3804.144067584771, 683.4225079807554, 700.3906601256939, 700.394159052416, 705.1432509500981, 722.552017748999, 460.39688008708697, 583.5244275915115, 585.8125399872085, 623.7349126621168, 625.8203286804315, 8185.672573097128, 8225.136360682161, 8226.506409615442, 8308.376871734645, 8476.745697629081, 562.9408912064174, 591.9200907877467, 604.4046614085123, 612.2010775937356, 631.7062565906875, 3285.3100848821537, 3778.5654649715084, 3859.305325041463, 3880.0, 3985.1431942132, 89054.55472722594, 91687.0, 92480.0, 94860.10279794365, 96143.0385696148, 73753.0, 75096.62451687707, 77010.6149469249, 77095.45809083899, 77943.61028194823, 66062.0, 67273.66363168153, 68625.65687171632, 71086.0, 71675.6416217926, 7535.13345965221, 7672.672597669101, 7748.845023099394, 7817.609119543887, 8181.79545511367, 49515.0, 49984.75007624939, 50578.0, 50634.74682626563, 51075.72310692114, 64391.814532055265, 66223.33776662171, 67048.32951670616, 67962.32037679758, 68337.63324733578, 8194.50832950025, 8696.695615653422, 9947.204223662235, 10295.279330447096, 10559.472026398496, 11933.52265909354, 12116.757664846476, 12138.211016283913, 12186.634400967985, 12415.93792031034, 11424.371659558805, 11661.183717139718, 11812.763744724885, 11910.940445297718, 12184.14710970259, 11556.191066625255, 11557.122205279495, 11691.181617286707, 11912.76174476523, 12197.268163910194, 11280.830787538358, 11317.20779545457, 11767.88232117668, 11775.352355620524, 11991.520339186334, 8089.393295502743, 9709.951450242703, 9877.111060004634, 9922.355046922215, 9939.701808945743, 7991.760247192594, 8816.294696424398, 8824.5587720615, 9919.702408927744, 9950.253730970351], "value": 7086.29665074459, "unit": "score"}], "name": "dromaeo_css", "value": 7700.669261392374, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 112.0, 117.0, 117.0, 116.0, 133.0, 116.0, 121.0, 166.0, 169.0], "value": 128.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 151.0, 129.0, 174.0, 109.0, 177.0, 166.0, 152.0, 179.0, 120.0], "value": 151.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 196.0, 201.0, 193.0, 195.0, 200.0, 200.0, 274.0, 274.0, 205.0], "value": 213.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 78.0, 73.0, 141.0, 55.0, 56.0, 60.0, 67.0, 128.0, 55.0], "value": 77.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 83.0, 130.0, 103.0, 114.0, 116.0, 128.0, 79.0, 77.0, 119.0], "value": 102.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 89.0, 90.0, 74.0, 137.0, 132.0, 92.0, 86.0], "value": 96.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 117.0, 94.0, 94.0, 92.0, 131.0, 108.0, 105.0, 150.0, 134.0], "value": 111.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 98.0, 92.0, 93.0, 90.0, 126.0, 150.0, 105.0, 136.0], "value": 107.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 63.0, 64.0, 64.0, 65.0, 65.0, 63.0, 79.0, 62.0, 59.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 66.0, 62.0, 66.0, 68.0, 69.0, 66.0, 64.0, 66.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 81.0, 80.0, 79.0, 82.0, 80.0, 81.0, 86.0, 82.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 124.0, 127.0, 123.0, 125.0, 133.0, 137.0, 140.0, 162.0, 139.0], "value": 133.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [214.0, 150.0, 221.0, 221.0, 210.0, 166.0, 167.0, 171.0, 175.0, 177.0], "value": 187.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 70.0, 66.0, 68.0, 68.0, 66.0, 67.0, 68.0, 67.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1587.4, "alertThreshold": 2.0}]} 03:57:55 INFO - Return code: 0 03:57:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:57:55 ERROR - # TBPL SUCCESS # 03:57:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:57:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:57:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:57:55 INFO - 2016-07-17 03:57:55.236 screenresolution[1686:10444] starting screenresolution argv=screenresolution get 03:57:55 INFO - 2016-07-17 03:57:55.239 screenresolution[1686:10444] Display 0: 1600x1200x32@60 03:57:55 INFO - 2016-07-17 03:57:55.245 screenresolution[1687:10447] starting screenresolution argv=screenresolution list 03:57:55 INFO - Available Modes on Display 0 03:57:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:57:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:57:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:57:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:57:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:57:55 INFO - Intel Iris: 03:57:55 INFO - Chipset Model: Intel Iris 03:57:55 INFO - Type: GPU 03:57:55 INFO - Bus: Built-In 03:57:55 INFO - VRAM (Dynamic, Max): 1536 MB 03:57:55 INFO - Vendor: Intel (0x8086) 03:57:55 INFO - Device ID: 0x0a2e 03:57:55 INFO - Revision ID: 0x0009 03:57:55 INFO - Displays: 03:57:55 INFO - Display: 03:57:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:57:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:57:55 INFO - Display Serial Number: 1600x1200 60 03:57:55 INFO - Main Display: Yes 03:57:55 INFO - Mirror: Off 03:57:55 INFO - Online: Yes 03:57:55 INFO - Rotation: Supported 03:57:55 INFO - Return code: 0 03:57:55 INFO - Running post-action listener: _resource_record_post_action 03:57:55 INFO - Running post-run listener: _resource_record_post_run 03:57:56 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 38405632; Write bytes: 497578496; Read time: 396; Write time: 1953 03:57:56 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 193491456; Write bytes: 188205056; Read time: 17587; Write time: 377 03:57:56 INFO - run-tests - Wall time: 1172s; CPU: 54.0%; Read bytes: 36574720; Write bytes: 299629056; Read time: 375; Write time: 1544 03:57:56 INFO - Running post-run listener: _upload_blobber_files 03:57:56 INFO - Blob upload gear active. 03:57:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:57:56 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:57:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:57:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:57:56 INFO - (blobuploader) - INFO - Open directory for files ... 03:57:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:57:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:57:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:57:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:57:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:57:57 INFO - (blobuploader) - INFO - Done attempting. 03:57:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:57:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:57:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:57:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:57:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:57:58 INFO - (blobuploader) - INFO - Done attempting. 03:57:58 INFO - (blobuploader) - INFO - Iteration through files over. 03:57:58 INFO - Return code: 0 03:57:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:57:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:57:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/953200fd3e1998220167ddebc6a114a98b9821f956312f160d1b979ffefacc4a020d9eb5cfb85074c1e3a60a17506678580ea9b66ef2a32eb13485ec3d8abed8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28278594e7a14a9789b86509f62a1980f956a4a0789f9841a1627150b08565f969df5b70f1056869d725abda18790743615a4cf784d8661f4ef2caa0e231bbed"} 03:57:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:57:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:57:58 INFO - Contents: 03:57:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/953200fd3e1998220167ddebc6a114a98b9821f956312f160d1b979ffefacc4a020d9eb5cfb85074c1e3a60a17506678580ea9b66ef2a32eb13485ec3d8abed8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28278594e7a14a9789b86509f62a1980f956a4a0789f9841a1627150b08565f969df5b70f1056869d725abda18790743615a4cf784d8661f4ef2caa0e231bbed"} 03:57:58 INFO - Running post-run listener: copy_logs_to_upload_dir 03:57:58 INFO - Copying logs to upload dir... 03:57:58 INFO - mkdir: /builds/slave/test/build/upload/logs 03:57:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.124964 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-07-17 03:57:58.788308) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 03:57:58.792173) ========= 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.3gPCeXIk5f/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.GPbzJ7ZlO4/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/953200fd3e1998220167ddebc6a114a98b9821f956312f160d1b979ffefacc4a020d9eb5cfb85074c1e3a60a17506678580ea9b66ef2a32eb13485ec3d8abed8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28278594e7a14a9789b86509f62a1980f956a4a0789f9841a1627150b08565f969df5b70f1056869d725abda18790743615a4cf784d8661f4ef2caa0e231bbed"} build_url:https://queue.taskcluster.net/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011094 build_url: 'https://queue.taskcluster.net/v1/task/I6rQ5njTTUiehnrarmSaJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/953200fd3e1998220167ddebc6a114a98b9821f956312f160d1b979ffefacc4a020d9eb5cfb85074c1e3a60a17506678580ea9b66ef2a32eb13485ec3d8abed8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28278594e7a14a9789b86509f62a1980f956a4a0789f9841a1627150b08565f969df5b70f1056869d725abda18790743615a4cf784d8661f4ef2caa0e231bbed"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 03:57:58.828681) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:57:58.829462) ========= 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.3gPCeXIk5f/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.GPbzJ7ZlO4/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.005292 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:57:58.879520) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 03:57:58.879863) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 03:57:58.880227) ========= ========= Total master_lag: 0.20 =========