builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0236 starttime: 1484329671.89 results: success (0) buildid: 20170113065316 builduid: 42512f0668e0481dbda4bf69edd9fcd2 revision: a72f87d02bf9fe5e383d2b0507794573b5111468 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 09:47:51.886789) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 09:47:51.887232) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 09:47:51.887516) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 09:47:51.914113) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 09:47:51.914493) ========= 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.FycmsRb41Z/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.5FwuIsPspK/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 --2017-01-13 09:47:51-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 38.8M=0s 2017-01-13 09:47:52 (38.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134913 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 09:47:52.073408) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 09:47:52.073742) ========= 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.FycmsRb41Z/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.5FwuIsPspK/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.025400 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 09:47:52.121997) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-13 09:47:52.122321) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a72f87d02bf9fe5e383d2b0507794573b5111468 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a72f87d02bf9fe5e383d2b0507794573b5111468 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FycmsRb41Z/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.5FwuIsPspK/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 2017-01-13 09:47:52,220 truncating revision to first 12 chars 2017-01-13 09:47:52,220 Setting DEBUG logging. 2017-01-13 09:47:52,220 attempt 1/10 2017-01-13 09:47:52,220 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a72f87d02bf9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-13 09:47:53,977 unpacking tar archive at: mozilla-aurora-a72f87d02bf9/testing/mozharness/ program finished with exit code 0 elapsedTime=2.029815 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-13 09:47:54.175423) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 09:47:54.175761) ========= script_repo_revision: a72f87d02bf9fe5e383d2b0507794573b5111468 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 09:47:54.176137) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 09:47:54.176399) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 09:47:54.196337) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-13 09:47:54.196645) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FycmsRb41Z/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.5FwuIsPspK/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 09:47:54 INFO - MultiFileLogger online at 20170113 09:47:54 in /builds/slave/test 09:47:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:47:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:47:54 INFO - {'append_to_log': False, 09:47:54 INFO - 'base_work_dir': '/builds/slave/test', 09:47:54 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:47:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:47:54 INFO - 'branch': 'Mozilla-Aurora', 09:47:54 INFO - 'buildbot_json_path': 'buildprops.json', 09:47:54 INFO - 'config_files': ('talos/mac_config.py',), 09:47:54 INFO - 'default_actions': ('clobber', 09:47:54 INFO - 'read-buildbot-config', 09:47:54 INFO - 'download-and-extract', 09:47:54 INFO - 'populate-webroot', 09:47:54 INFO - 'create-virtualenv', 09:47:54 INFO - 'install', 09:47:54 INFO - 'run-tests'), 09:47:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:47:54 INFO - 'download_minidump_stackwalk': True, 09:47:54 INFO - 'download_symbols': 'ondemand', 09:47:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:47:54 INFO - 'tooltool.py': '/tools/tooltool.py', 09:47:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:47:54 INFO - '/tools/misc-python/virtualenv.py')}, 09:47:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:47:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:47:54 INFO - 'installer_path': 'installer.exe', 09:47:54 INFO - 'log_level': 'info', 09:47:54 INFO - 'log_name': 'talos', 09:47:54 INFO - 'log_to_console': True, 09:47:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:47:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:47:54 INFO - 'opt_config_files': (), 09:47:54 INFO - 'pip_index': False, 09:47:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:47:54 INFO - 'cmd': ('bash', 09:47:54 INFO - '-c', 09:47:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:47:54 INFO - 'enabled': True, 09:47:54 INFO - 'halt_on_failure': False, 09:47:54 INFO - 'name': 'check_screen_resolution'},), 09:47:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:47:54 INFO - 'cmd': ('bash', 09:47:54 INFO - '-c', 09:47:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:47:54 INFO - 'enabled': True, 09:47:54 INFO - 'halt_on_failure': False, 09:47:54 INFO - 'name': 'check_screen_resolution'},), 09:47:54 INFO - 'run_cmd_checks_enabled': True, 09:47:54 INFO - 'sps_profile': False, 09:47:54 INFO - 'sps_profile_interval': 0, 09:47:54 INFO - 'suite': 'dromaeojs', 09:47:54 INFO - 'system_bits': '32', 09:47:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:47:54 INFO - 'title': 't-yosemite-r7-0236', 09:47:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:47:54 INFO - 'use_talos_json': True, 09:47:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:47:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:47:54 INFO - 'work_dir': 'build'} 09:47:54 INFO - [mozharness: 2017-01-13 17:47:54.399220Z] Running clobber step. 09:47:54 INFO - Running pre-action listener: _resource_record_pre_action 09:47:54 INFO - Running main action method: clobber 09:47:54 INFO - rmtree: /builds/slave/test/build 09:47:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:47:56 INFO - Running post-action listener: _resource_record_post_action 09:47:56 INFO - [mozharness: 2017-01-13 17:47:56.722329Z] Finished clobber step (success) 09:47:56 INFO - [mozharness: 2017-01-13 17:47:56.722490Z] Running read-buildbot-config step. 09:47:56 INFO - Running pre-action listener: _resource_record_pre_action 09:47:56 INFO - Running main action method: read_buildbot_config 09:47:56 INFO - Using buildbot properties: 09:47:56 INFO - { 09:47:56 INFO - "project": "", 09:47:56 INFO - "product": "firefox", 09:47:56 INFO - "script_repo_revision": "production", 09:47:56 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:47:56 INFO - "repository": "", 09:47:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:47:56 INFO - "buildid": "20170113065316", 09:47:56 INFO - "pgo_build": "False", 09:47:56 INFO - "basedir": "/builds/slave/test", 09:47:56 INFO - "buildnumber": 84, 09:47:56 INFO - "slavename": "t-yosemite-r7-0236", 09:47:56 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:47:56 INFO - "platform": "yosemite_r7", 09:47:56 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:47:56 INFO - "branch": "mozilla-aurora", 09:47:56 INFO - "repo_path": "releases/mozilla-aurora", 09:47:56 INFO - "revision": "a72f87d02bf9fe5e383d2b0507794573b5111468", 09:47:56 INFO - "stage_platform": "macosx64", 09:47:56 INFO - "builduid": "42512f0668e0481dbda4bf69edd9fcd2", 09:47:56 INFO - "slavebuilddir": "test" 09:47:56 INFO - } 09:47:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:47:56 INFO - Running post-action listener: _resource_record_post_action 09:47:56 INFO - [mozharness: 2017-01-13 17:47:56.725872Z] Finished read-buildbot-config step (success) 09:47:56 INFO - [mozharness: 2017-01-13 17:47:56.726004Z] Running download-and-extract step. 09:47:56 INFO - Running pre-action listener: _resource_record_pre_action 09:47:56 INFO - Running main action method: download_and_extract 09:47:56 INFO - mkdir: /builds/slave/test/build/tests 09:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:56 INFO - trying https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:47:56 INFO - Downloading https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:47:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 09:47:58 INFO - Downloaded 1199 bytes. 09:47:58 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:47:58 INFO - Using the following test package requirements: 09:47:58 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:47:58 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:58 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:47:58 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:58 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:47:58 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:58 INFO - u'jsshell-mac.zip'], 09:47:58 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:58 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:47:58 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:47:58 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:58 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:47:58 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:58 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:47:58 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:58 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:47:58 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:58 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:47:58 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:47:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:47:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:47:58 INFO - Fetch https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:47:58 INFO - Http code: 200 09:47:58 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 09:47:58 INFO - x-amz-cf-id: -oRem0tWd9xnq7k-mshy5qOX1rAdwcqYH6DbKMyBtVGLzj6n9BAhQw== 09:47:58 INFO - x-amz-version-id: Q_..PNOKwl3UzoEDtsU6RknSmDLkh71Y 09:47:59 INFO - Expected file size: 16275790 09:47:59 INFO - Obtained file size: 16275790 09:48:05 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:48:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:48:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:48:05 INFO - Fetch https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:48:06 INFO - Http code: 200 09:48:06 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 09:48:06 INFO - x-amz-cf-id: qVUQv5yiErBUxsDcNwm7bVeyrmBflKVfNjB6Ta0pvkaJRlerzkVJUQ== 09:48:06 INFO - x-amz-version-id: Q_..PNOKwl3UzoEDtsU6RknSmDLkh71Y 09:48:06 INFO - Expected file size: 16275790 09:48:06 INFO - Obtained file size: 16275790 09:48:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:48:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:48:12 INFO - Fetch https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:48:13 INFO - Http code: 200 09:48:13 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 09:48:13 INFO - x-amz-cf-id: ngGzqeZ_Ij7p5dBDECKdPuP4e7g__ekGAwsQF0jrb669KoB2DkRTPQ== 09:48:13 INFO - x-amz-version-id: HdjKzFnQu6Ue4iY3wdiP6RI6WpBCY2hW 09:48:13 INFO - Expected file size: 14409038 09:48:13 INFO - Obtained file size: 14409038 09:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:13 INFO - trying https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:48:13 INFO - Downloading https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:48:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 09:48:16 INFO - Downloaded 109998895 bytes. 09:48:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:48:16 INFO - mkdir: /builds/slave/test/properties 09:48:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:48:16 INFO - Writing to file /builds/slave/test/properties/build_url 09:48:16 INFO - Contents: 09:48:16 INFO - build_url:https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:48:17 INFO - Running post-action listener: _resource_record_post_action 09:48:17 INFO - Running post-action listener: set_extra_try_arguments 09:48:17 INFO - [mozharness: 2017-01-13 17:48:17.337292Z] Finished download-and-extract step (success) 09:48:17 INFO - [mozharness: 2017-01-13 17:48:17.337445Z] Running populate-webroot step. 09:48:17 INFO - Running pre-action listener: _resource_record_pre_action 09:48:17 INFO - Running main action method: populate_webroot 09:48:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:48:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:48:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:48:17 INFO - '--tppagecycles', 09:48:17 INFO - '7'], 09:48:17 INFO - 'tests': ['tsvgm']}}, 09:48:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:48:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:48:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:48:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:48:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:48:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:48:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:17 INFO - 'talos_options': ['--disable-e10s'], 09:48:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:17 INFO - 'talos_options': ['--disable-e10s'], 09:48:17 INFO - 'tests': ['damp', 'tps']}, 09:48:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:17 INFO - 'tests': ['damp', 'tps']}, 09:48:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:48:17 INFO - 'tests': ['dromaeo_dom']}, 09:48:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:48:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:48:17 INFO - 'tests': ['basic_compositor_video']}, 09:48:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:48:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:48:17 INFO - 'tests': ['a11yr', 09:48:17 INFO - 'ts_paint', 09:48:17 INFO - 'tpaint', 09:48:17 INFO - 'sessionrestore', 09:48:17 INFO - 'sessionrestore_no_auto_restore', 09:48:17 INFO - 'tabpaint']}, 09:48:17 INFO - 'other-e10s': {'tests': ['a11yr', 09:48:17 INFO - 'ts_paint', 09:48:17 INFO - 'tpaint', 09:48:17 INFO - 'sessionrestore', 09:48:17 INFO - 'sessionrestore_no_auto_restore', 09:48:17 INFO - 'tabpaint']}, 09:48:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:48:17 INFO - 'ts_paint', 09:48:17 INFO - 'tpaint', 09:48:17 INFO - 'sessionrestore', 09:48:17 INFO - 'sessionrestore_no_auto_restore', 09:48:17 INFO - 'tabpaint']}, 09:48:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:48:17 INFO - 'ts_paint', 09:48:17 INFO - 'tpaint', 09:48:17 INFO - 'sessionrestore', 09:48:17 INFO - 'sessionrestore_no_auto_restore', 09:48:17 INFO - 'tabpaint']}, 09:48:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:48:17 INFO - 'tests': ['a11yr', 09:48:17 INFO - 'ts_paint', 09:48:17 INFO - 'tpaint', 09:48:17 INFO - 'sessionrestore', 09:48:17 INFO - 'sessionrestore_no_auto_restore', 09:48:17 INFO - 'tabpaint']}, 09:48:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:48:17 INFO - 'tests': ['a11yr', 09:48:17 INFO - 'ts_paint', 09:48:17 INFO - 'tpaint', 09:48:17 INFO - 'sessionrestore', 09:48:17 INFO - 'sessionrestore_no_auto_restore', 09:48:17 INFO - 'tabpaint']}, 09:48:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:48:17 INFO - 'tests': ['tsvgx', 09:48:17 INFO - 'tsvgr_opacity', 09:48:17 INFO - 'tart', 09:48:17 INFO - 'tscrollx', 09:48:17 INFO - 'cart']}, 09:48:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:48:17 INFO - 'tsvgr_opacity', 09:48:17 INFO - 'tart', 09:48:17 INFO - 'tscrollx', 09:48:17 INFO - 'cart']}, 09:48:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:17 INFO - 'talos_options': ['--disable-e10s'], 09:48:17 INFO - 'tests': ['tp5o']}, 09:48:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:17 INFO - 'tests': ['tp5o']}, 09:48:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:17 INFO - 'talos_options': ['--disable-e10s', 09:48:17 INFO - '--xperf_path', 09:48:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:17 INFO - 'tests': ['tp5n']}, 09:48:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:17 INFO - 'talos_options': ['--xperf_path', 09:48:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:17 INFO - 'tests': ['tp5n']}}, 09:48:17 INFO - 'talos.zip': {'path': '', 09:48:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:48:17 INFO - Running post-action listener: _resource_record_post_action 09:48:17 INFO - [mozharness: 2017-01-13 17:48:17.356530Z] Finished populate-webroot step (success) 09:48:17 INFO - [mozharness: 2017-01-13 17:48:17.356659Z] Running create-virtualenv step. 09:48:17 INFO - Running pre-action listener: _resource_record_pre_action 09:48:17 INFO - Running main action method: create_virtualenv 09:48:17 INFO - Creating virtualenv /builds/slave/test/build/venv 09:48:17 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 09:48:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:48:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:48:17 INFO - Using real prefix '/tools/python27' 09:48:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:48:18 INFO - Installing distribute.............................................................................................................................................................................................done. 09:48:21 INFO - Installing pip.................done. 09:48:21 INFO - Return code: 0 09:48:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:48:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:48:21 INFO - Reading from file tmpfile_stdout 09:48:21 INFO - Output received: 09:48:21 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:48:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:48:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:21 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 0x10c9b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe123c9b0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c9d30>, '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 0x10c62fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c1b20>, '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.FycmsRb41Z/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.5FwuIsPspK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 09:48:21 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 09:48:21 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 09:48:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FycmsRb41Z/Render', 09:48:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:21 INFO - 'HOME': '/Users/cltbld', 09:48:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:21 INFO - 'LOGNAME': 'cltbld', 09:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:21 INFO - 'MOZ_NO_REMOTE': '1', 09:48:21 INFO - 'NO_EM_RESTART': '1', 09:48:21 INFO - 'PAGER': '/bin/cat', 09:48:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:21 INFO - 'PWD': '/builds/slave/test', 09:48:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:21 INFO - 'SHELL': '/bin/bash', 09:48:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FwuIsPspK/Listeners', 09:48:21 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 09:48:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:21 INFO - 'USER': 'cltbld', 09:48:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:21 INFO - 'XPC_FLAGS': '0x0', 09:48:21 INFO - 'XPC_SERVICE_NAME': '0', 09:48:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:21 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 09:48:21 INFO - Cleaning up... 09:48:21 INFO - Return code: 0 09:48:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:48:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe123c9b0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c9d30>, '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 0x10c62fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c1b20>, '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.FycmsRb41Z/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.5FwuIsPspK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 09:48:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:48:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:48:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FycmsRb41Z/Render', 09:48:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:21 INFO - 'HOME': '/Users/cltbld', 09:48:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:21 INFO - 'LOGNAME': 'cltbld', 09:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:21 INFO - 'MOZ_NO_REMOTE': '1', 09:48:21 INFO - 'NO_EM_RESTART': '1', 09:48:21 INFO - 'PAGER': '/bin/cat', 09:48:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:21 INFO - 'PWD': '/builds/slave/test', 09:48:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:21 INFO - 'SHELL': '/bin/bash', 09:48:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FwuIsPspK/Listeners', 09:48:21 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 09:48:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:21 INFO - 'USER': 'cltbld', 09:48:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:21 INFO - 'XPC_FLAGS': '0x0', 09:48:21 INFO - 'XPC_SERVICE_NAME': '0', 09:48:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:21 INFO - Downloading/unpacking psutil>=3.1.1 09:48:21 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 09:48:21 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 09:48:21 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 09:48:21 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 09:48:21 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 09:48:21 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 09:48:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:48:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:25 INFO - Installing collected packages: psutil 09:48:25 INFO - Running setup.py install for psutil 09:48:25 INFO - building 'psutil._psutil_osx' extension 09:48:25 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 09:48:25 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 09:48:25 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 09:48:25 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 09:48:26 INFO - building 'psutil._psutil_posix' extension 09:48:26 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 09:48:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:48:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:48:26 INFO - ^ 09:48:26 INFO - 1 warning generated. 09:48:26 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 09:48:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:26 INFO - Successfully installed psutil 09:48:26 INFO - Cleaning up... 09:48:26 INFO - Return code: 0 09:48:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:48:26 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:26 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe123c9b0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c9d30>, '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 0x10c62fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c1b20>, '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.FycmsRb41Z/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.5FwuIsPspK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 09:48:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:48:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:48:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FycmsRb41Z/Render', 09:48:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:26 INFO - 'HOME': '/Users/cltbld', 09:48:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:26 INFO - 'LOGNAME': 'cltbld', 09:48:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:26 INFO - 'MOZ_NO_REMOTE': '1', 09:48:26 INFO - 'NO_EM_RESTART': '1', 09:48:26 INFO - 'PAGER': '/bin/cat', 09:48:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:26 INFO - 'PWD': '/builds/slave/test', 09:48:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:26 INFO - 'SHELL': '/bin/bash', 09:48:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FwuIsPspK/Listeners', 09:48:26 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 09:48:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:26 INFO - 'USER': 'cltbld', 09:48:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:26 INFO - 'XPC_FLAGS': '0x0', 09:48:26 INFO - 'XPC_SERVICE_NAME': '0', 09:48:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:48:26 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 09:48:26 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 09:48:26 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 09:48:26 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 09:48:26 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 09:48:26 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 09:48:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:48:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:48:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:48:29 INFO - Installing collected packages: mozsystemmonitor 09:48:29 INFO - Running setup.py install for mozsystemmonitor 09:48:29 INFO - Successfully installed mozsystemmonitor 09:48:29 INFO - Cleaning up... 09:48:29 INFO - Return code: 0 09:48:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:48:29 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:29 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe123c9b0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c9d30>, '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 0x10c62fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c1b20>, '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.FycmsRb41Z/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.5FwuIsPspK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 09:48:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:48:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:48:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FycmsRb41Z/Render', 09:48:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:29 INFO - 'HOME': '/Users/cltbld', 09:48:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:29 INFO - 'LOGNAME': 'cltbld', 09:48:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:29 INFO - 'MOZ_NO_REMOTE': '1', 09:48:29 INFO - 'NO_EM_RESTART': '1', 09:48:29 INFO - 'PAGER': '/bin/cat', 09:48:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:29 INFO - 'PWD': '/builds/slave/test', 09:48:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:29 INFO - 'SHELL': '/bin/bash', 09:48:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FwuIsPspK/Listeners', 09:48:29 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 09:48:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:29 INFO - 'USER': 'cltbld', 09:48:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:29 INFO - 'XPC_FLAGS': '0x0', 09:48:29 INFO - 'XPC_SERVICE_NAME': '0', 09:48:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:29 INFO - Downloading/unpacking jsonschema==2.5.1 09:48:29 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 09:48:29 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 09:48:29 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 09:48:29 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 09:48:29 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 09:48:29 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 09:48:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:48:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:33 INFO - Downloading functools32-3.2.3-2.tar.gz 09:48:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:48:33 INFO - warning: no files found matching '*.txt' 09:48:33 INFO - no previously-included directories found matching 'build' 09:48:33 INFO - no previously-included directories found matching 'dist' 09:48:33 INFO - no previously-included directories found matching '.git*' 09:48:33 INFO - Installing collected packages: jsonschema, functools32 09:48:33 INFO - Running setup.py install for functools32 09:48:33 INFO - warning: no files found matching '*.txt' 09:48:33 INFO - no previously-included directories found matching 'build' 09:48:33 INFO - no previously-included directories found matching 'dist' 09:48:33 INFO - no previously-included directories found matching '.git*' 09:48:33 INFO - Successfully installed jsonschema functools32 09:48:33 INFO - Cleaning up... 09:48:33 INFO - Return code: 0 09:48:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:48:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe123c9b0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c9d30>, '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 0x10c62fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c1b20>, '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.FycmsRb41Z/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.5FwuIsPspK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 09:48:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:48:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:48:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FycmsRb41Z/Render', 09:48:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:33 INFO - 'HOME': '/Users/cltbld', 09:48:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:33 INFO - 'LOGNAME': 'cltbld', 09:48:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:33 INFO - 'MOZ_NO_REMOTE': '1', 09:48:33 INFO - 'NO_EM_RESTART': '1', 09:48:33 INFO - 'PAGER': '/bin/cat', 09:48:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:33 INFO - 'PWD': '/builds/slave/test', 09:48:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:33 INFO - 'SHELL': '/bin/bash', 09:48:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FwuIsPspK/Listeners', 09:48:33 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 09:48:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:33 INFO - 'USER': 'cltbld', 09:48:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:33 INFO - 'XPC_FLAGS': '0x0', 09:48:33 INFO - 'XPC_SERVICE_NAME': '0', 09:48:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:48:33 INFO - Cleaning up... 09:48:33 INFO - Return code: 0 09:48:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:48:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:33 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 0x10c9b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe123c9b0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c9d30>, '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 0x10c62fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c1b20>, '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.FycmsRb41Z/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.5FwuIsPspK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 09:48:33 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 09:48:33 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 09:48:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FycmsRb41Z/Render', 09:48:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:33 INFO - 'HOME': '/Users/cltbld', 09:48:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:33 INFO - 'LOGNAME': 'cltbld', 09:48:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:33 INFO - 'MOZ_NO_REMOTE': '1', 09:48:33 INFO - 'NO_EM_RESTART': '1', 09:48:33 INFO - 'PAGER': '/bin/cat', 09:48:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:33 INFO - 'PWD': '/builds/slave/test', 09:48:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:33 INFO - 'SHELL': '/bin/bash', 09:48:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FwuIsPspK/Listeners', 09:48:33 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 09:48:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:33 INFO - 'USER': 'cltbld', 09:48:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:33 INFO - 'XPC_FLAGS': '0x0', 09:48:33 INFO - 'XPC_SERVICE_NAME': '0', 09:48:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:33 INFO - Downloading/unpacking blobuploader==1.2.4 09:48:33 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 09:48:33 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 09:48:33 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 09:48:33 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 09:48:33 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 09:48:33 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 09:48:36 INFO - Downloading blobuploader-1.2.4.tar.gz 09:48:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:48:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:48:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:37 INFO - Downloading docopt-0.6.1.tar.gz 09:48:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:48:37 INFO - Installing collected packages: blobuploader, requests, docopt 09:48:37 INFO - Running setup.py install for blobuploader 09:48:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:48:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:48:37 INFO - Running setup.py install for requests 09:48:38 INFO - Running setup.py install for docopt 09:48:38 INFO - Successfully installed blobuploader requests docopt 09:48:38 INFO - Cleaning up... 09:48:38 INFO - Return code: 0 09:48:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:38 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 0x10c9b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe123c9b0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c9d30>, '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 0x10c62fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c1b20>, '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.FycmsRb41Z/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.5FwuIsPspK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 09:48:38 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 09:48:38 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 09:48:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FycmsRb41Z/Render', 09:48:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:38 INFO - 'HOME': '/Users/cltbld', 09:48:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:38 INFO - 'LOGNAME': 'cltbld', 09:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:38 INFO - 'MOZ_NO_REMOTE': '1', 09:48:38 INFO - 'NO_EM_RESTART': '1', 09:48:38 INFO - 'PAGER': '/bin/cat', 09:48:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:38 INFO - 'PWD': '/builds/slave/test', 09:48:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:38 INFO - 'SHELL': '/bin/bash', 09:48:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FwuIsPspK/Listeners', 09:48:38 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 09:48:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:38 INFO - 'USER': 'cltbld', 09:48:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:38 INFO - 'XPC_FLAGS': '0x0', 09:48:38 INFO - 'XPC_SERVICE_NAME': '0', 09:48:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:48:38 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-tfefic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:48:39 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-s5lI_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:48:39 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-1b94gz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:48:39 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-omkyNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:48:39 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-AVxllx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:48:39 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-91r3r_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:48:39 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-TDfcDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:48:39 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-oZYpq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:48:39 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-DWe_vC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:48:39 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-7YWiu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:48:40 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-6p5bOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:48:40 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-kU0mkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:48:40 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Njf1A1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:48:40 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-zLGU9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:40 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-ifrYqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:48:40 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-39NZiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:48:40 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Qi6pBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:48:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:48:40 INFO - Running setup.py install for manifestparser 09:48:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:48:40 INFO - Running setup.py install for mozcrash 09:48:41 INFO - Running setup.py install for mozdebug 09:48:41 INFO - Running setup.py install for mozdevice 09:48:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:48:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:48:41 INFO - Running setup.py install for mozfile 09:48:41 INFO - Running setup.py install for mozhttpd 09:48:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:48:41 INFO - Running setup.py install for mozinfo 09:48:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:48:41 INFO - Running setup.py install for mozInstall 09:48:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:48:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:48:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:48:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:48:41 INFO - Running setup.py install for mozleak 09:48:42 INFO - Running setup.py install for mozlog 09:48:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:48:42 INFO - Running setup.py install for moznetwork 09:48:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:48:42 INFO - Running setup.py install for mozprocess 09:48:42 INFO - Running setup.py install for mozprofile 09:48:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:48:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:48:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:48:42 INFO - Running setup.py install for mozrunner 09:48:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:48:42 INFO - Running setup.py install for mozscreenshot 09:48:43 INFO - Running setup.py install for moztest 09:48:43 INFO - Running setup.py install for mozversion 09:48:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:48:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:48:43 INFO - Cleaning up... 09:48:43 INFO - Return code: 0 09:48:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:43 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 0x10c9b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe123c9b0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c9d30>, '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 0x10c62fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c1b20>, '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.FycmsRb41Z/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.5FwuIsPspK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 09:48:43 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 09:48:43 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 09:48:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FycmsRb41Z/Render', 09:48:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:43 INFO - 'HOME': '/Users/cltbld', 09:48:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:43 INFO - 'LOGNAME': 'cltbld', 09:48:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:43 INFO - 'MOZ_NO_REMOTE': '1', 09:48:43 INFO - 'NO_EM_RESTART': '1', 09:48:43 INFO - 'PAGER': '/bin/cat', 09:48:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:43 INFO - 'PWD': '/builds/slave/test', 09:48:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:43 INFO - 'SHELL': '/bin/bash', 09:48:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FwuIsPspK/Listeners', 09:48:43 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 09:48:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:43 INFO - 'USER': 'cltbld', 09:48:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:43 INFO - 'XPC_FLAGS': '0x0', 09:48:43 INFO - 'XPC_SERVICE_NAME': '0', 09:48:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:48:43 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-2rL1NL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:48:43 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)) 09:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:48:43 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-9jqV9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 09:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:48:43 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-AybfmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:48:43 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)) 09:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:48:43 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-KF5wx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:48:44 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)) 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:48:44 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-SH5sWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:48:44 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)) 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:48:44 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-3p4ykj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:48:44 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)) 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:48:44 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-etPmSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:48:44 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)) 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:48:44 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-WGuQOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:48:44 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)) 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:48:44 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-tonuV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:48:44 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)) 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:48:44 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-uJsEz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:48:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:48:44 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-XaNuVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:48:44 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)) 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:48:44 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-zIsqSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:48:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:48:44 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-_DeiY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:48:45 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)) 09:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:48:45 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-krW_gF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:48:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 09:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:45 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-TbIVXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:45 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)) 09:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:48:45 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-sTfa8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:48:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 09:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:48:45 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-ZSZJPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:48:45 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)) 09:48:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:48:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:48:45 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)) 09:48:45 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)) 09:48:45 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)) 09:48:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:48 INFO - Downloading blessings-1.6.tar.gz 09:48:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:48:48 INFO - Installing collected packages: blessings 09:48:48 INFO - Running setup.py install for blessings 09:48:48 INFO - Successfully installed blessings 09:48:48 INFO - Cleaning up... 09:48:48 INFO - Return code: 0 09:48:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:48:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:48:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:48:48 INFO - Reading from file tmpfile_stdout 09:48:48 INFO - Current package versions: 09:48:48 INFO - blessings == 1.6 09:48:48 INFO - blobuploader == 1.2.4 09:48:48 INFO - docopt == 0.6.1 09:48:48 INFO - functools32 == 3.2.3-2 09:48:48 INFO - jsonschema == 2.5.1 09:48:48 INFO - manifestparser == 1.1 09:48:48 INFO - mozInstall == 1.12 09:48:48 INFO - mozcrash == 1.0 09:48:48 INFO - mozdebug == 0.1 09:48:48 INFO - mozdevice == 0.48 09:48:48 INFO - mozfile == 1.2 09:48:48 INFO - mozhttpd == 0.7 09:48:48 INFO - mozinfo == 0.9 09:48:48 INFO - mozleak == 0.1 09:48:48 INFO - mozlog == 3.4 09:48:48 INFO - moznetwork == 0.27 09:48:48 INFO - mozprocess == 0.23 09:48:48 INFO - mozprofile == 0.28 09:48:48 INFO - mozrunner == 6.13 09:48:48 INFO - mozscreenshot == 0.1 09:48:48 INFO - mozsystemmonitor == 0.3 09:48:48 INFO - moztest == 0.8 09:48:48 INFO - mozversion == 1.4 09:48:48 INFO - psutil == 3.1.1 09:48:48 INFO - requests == 1.2.3 09:48:48 INFO - wsgiref == 0.1.2 09:48:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/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 0x10c9b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe123c9b0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c9d30>, '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 0x10c62fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c1b20>, '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.FycmsRb41Z/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.5FwuIsPspK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 09:48:49 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 09:48:49 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 09:48:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FycmsRb41Z/Render', 09:48:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:49 INFO - 'HOME': '/Users/cltbld', 09:48:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:49 INFO - 'LOGNAME': 'cltbld', 09:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:49 INFO - 'MOZ_NO_REMOTE': '1', 09:48:49 INFO - 'NO_EM_RESTART': '1', 09:48:49 INFO - 'PAGER': '/bin/cat', 09:48:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:49 INFO - 'PWD': '/builds/slave/test', 09:48:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:49 INFO - 'SHELL': '/bin/bash', 09:48:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FwuIsPspK/Listeners', 09:48:49 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 09:48:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:49 INFO - 'USER': 'cltbld', 09:48:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:49 INFO - 'XPC_FLAGS': '0x0', 09:48:49 INFO - 'XPC_SERVICE_NAME': '0', 09:48:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 INFO - Cleaning up... 09:48:49 INFO - Return code: 0 09:48:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:50 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 0x10c9b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe123c9b0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c9d30>, '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 0x10c62fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c1b20>, '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.FycmsRb41Z/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.5FwuIsPspK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 09:48:50 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 09:48:50 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 09:48:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FycmsRb41Z/Render', 09:48:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:50 INFO - 'HOME': '/Users/cltbld', 09:48:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:50 INFO - 'LOGNAME': 'cltbld', 09:48:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:50 INFO - 'MOZ_NO_REMOTE': '1', 09:48:50 INFO - 'NO_EM_RESTART': '1', 09:48:50 INFO - 'PAGER': '/bin/cat', 09:48:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:50 INFO - 'PWD': '/builds/slave/test', 09:48:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:50 INFO - 'SHELL': '/bin/bash', 09:48:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FwuIsPspK/Listeners', 09:48:50 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 09:48:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:50 INFO - 'USER': 'cltbld', 09:48:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:50 INFO - 'XPC_FLAGS': '0x0', 09:48:50 INFO - 'XPC_SERVICE_NAME': '0', 09:48:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:48:50 INFO - Cleaning up... 09:48:50 INFO - Return code: 0 09:48:50 INFO - Running post-action listener: _resource_record_post_action 09:48:50 INFO - Running post-action listener: _start_resource_monitoring 09:48:50 INFO - Starting resource monitoring. 09:48:50 INFO - [mozharness: 2017-01-13 17:48:50.514200Z] Finished create-virtualenv step (success) 09:48:50 INFO - [mozharness: 2017-01-13 17:48:50.515259Z] Running install step. 09:48:50 INFO - Running pre-action listener: _resource_record_pre_action 09:48:50 INFO - Running main action method: install 09:48:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:48:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:48:50 INFO - Reading from file tmpfile_stdout 09:48:50 INFO - Detecting whether we're running mozinstall >=1.0... 09:48:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:48:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:48:50 INFO - Reading from file tmpfile_stdout 09:48:50 INFO - Output received: 09:48:50 INFO - Usage: mozinstall [options] installer 09:48:50 INFO - Options: 09:48:50 INFO - -h, --help show this help message and exit 09:48:50 INFO - -d DEST, --destination=DEST 09:48:50 INFO - Directory to install application into. [default: 09:48:50 INFO - "/builds/slave/test"] 09:48:50 INFO - --app=APP Application being installed. [default: firefox] 09:48:50 INFO - mkdir: /builds/slave/test/build/application 09:48:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:48:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:49:22 INFO - Reading from file tmpfile_stdout 09:49:22 INFO - Output received: 09:49:22 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:49:22 INFO - Running post-action listener: _resource_record_post_action 09:49:22 INFO - [mozharness: 2017-01-13 17:49:22.184182Z] Finished install step (success) 09:49:22 INFO - [mozharness: 2017-01-13 17:49:22.184336Z] Running run-tests step. 09:49:22 INFO - Running pre-action listener: _resource_record_pre_action 09:49:22 INFO - Running main action method: run_tests 09:49:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:49:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:49:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:49:22 INFO - 2017-01-13 09:49:22.199 screenresolution[1622:7366] starting screenresolution argv=screenresolution get 09:49:22 INFO - 2017-01-13 09:49:22.202 screenresolution[1622:7366] Display 0: 1600x1200x32@60 09:49:22 INFO - 2017-01-13 09:49:22.208 screenresolution[1623:7368] starting screenresolution argv=screenresolution list 09:49:22 INFO - Available Modes on Display 0 09:49:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:49:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:49:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:49:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:49:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:49:22 INFO - Intel Iris: 09:49:22 INFO - Chipset Model: Intel Iris 09:49:22 INFO - Type: GPU 09:49:22 INFO - Bus: Built-In 09:49:22 INFO - VRAM (Dynamic, Max): 1536 MB 09:49:22 INFO - Vendor: Intel (0x8086) 09:49:22 INFO - Device ID: 0x0a2e 09:49:22 INFO - Revision ID: 0x0009 09:49:22 INFO - Displays: 09:49:22 INFO - Display: 09:49:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:49:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:49:22 INFO - Display Serial Number: 1600x1200 60 09:49:22 INFO - Main Display: Yes 09:49:22 INFO - Mirror: Off 09:49:22 INFO - Online: Yes 09:49:22 INFO - Rotation: Supported 09:49:22 INFO - Return code: 0 09:49:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:49:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:49:22 INFO - Python 2.7.3 09:49:22 INFO - Return code: 0 09:49:22 INFO - grabbing minidump binary from tooltool 09:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:22 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c9d30>, '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 0x10c62fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7c1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:49:22 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 09:49:22 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 09:49:22 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:49:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:49:22 INFO - Return code: 0 09:49:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:49:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:49:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:49:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:49:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:49:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:49:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0236 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:49:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FycmsRb41Z/Render', 09:49:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:22 INFO - 'HOME': '/Users/cltbld', 09:49:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:22 INFO - 'LOGNAME': 'cltbld', 09:49:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:49:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:22 INFO - 'MOZ_NO_REMOTE': '1', 09:49:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:49:22 INFO - 'NO_EM_RESTART': '1', 09:49:22 INFO - 'PAGER': '/bin/cat', 09:49:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:22 INFO - 'PWD': '/builds/slave/test', 09:49:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:49:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:22 INFO - 'SHELL': '/bin/bash', 09:49:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5FwuIsPspK/Listeners', 09:49:22 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 09:49:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:22 INFO - 'USER': 'cltbld', 09:49:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:22 INFO - 'XPC_FLAGS': '0x0', 09:49:22 INFO - 'XPC_SERVICE_NAME': '0', 09:49:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:49:22 INFO - mozversion application_buildid: 20170113065316 09:49:22 INFO - mozversion application_changeset: a72f87d02bf9fe5e383d2b0507794573b5111468 09:49:22 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:49:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:49:22 INFO - mozversion application_name: Firefox 09:49:22 INFO - mozversion application_remotingname: firefox-dev 09:49:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:49:22 INFO - mozversion application_vendor: Mozilla 09:49:22 INFO - mozversion application_version: 52.0a2 09:49:22 INFO - mozversion platform_buildid: 20170113065316 09:49:22 INFO - mozversion platform_changeset: a72f87d02bf9fe5e383d2b0507794573b5111468 09:49:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:49:22 INFO - mozversion platform_version: 52.0a2 09:49:22 INFO - using testdate: 1484329762 09:49:22 INFO - actual date: 1484329762 09:49:22 INFO - starting webserver on 'localhost:49252' 09:49:22 INFO - SUITE-START | Running 2 tests 09:49:22 INFO - TEST-START | dromaeo_css 09:49:22 INFO - operating with platform_type : mac_ 09:49:22 INFO - Initialising browser for dromaeo_css test... 09:49:22 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpyIQlv9/profile http://localhost:49252/getInfo.html) 09:49:25 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 09:49:25 INFO - PROCESS | 1633 | colorDepth:24 09:49:25 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 09:49:25 INFO - PROCESS | 1633 | __metrics 09:49:25 INFO - PROCESS | 1633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:49:25 INFO - PROCESS | 1633 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:49:26 INFO - TEST-INFO | 1633: exit 0 09:49:26 INFO - Browser initialized. 09:49:26 INFO - Running cycle 1/1 for dromaeo_css test... 09:49:26 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpyIQlv9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:38 INFO - PROCESS | 1635 | RSS: Main: 222117888 09:49:38 INFO - PROCESS | 1635 | 09:52:42 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49252/tests/dromaeo/cssquery-ext.html) 09:52:44 INFO - PROCESS | 1635 | RSS: Main: 365264896 09:52:44 INFO - PROCESS | 1635 | 09:55:47 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-ext.html (next: http://localhost:49252/tests/dromaeo/cssquery-jquery.html) 09:55:47 INFO - PROCESS | 1635 | RSS: Main: 349159424 09:55:47 INFO - PROCESS | 1635 | 09:58:50 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49252/tests/dromaeo/cssquery-mootools.html) 09:58:50 INFO - PROCESS | 1635 | RSS: Main: 319954944 09:58:50 INFO - PROCESS | 1635 | 10:01:53 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49252/tests/dromaeo/cssquery-prototype.html) 10:01:53 INFO - PROCESS | 1635 | RSS: Main: 317841408 10:01:53 INFO - PROCESS | 1635 | 10:04:56 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49252/tests/dromaeo/cssquery-yui.html) 10:04:57 INFO - PROCESS | 1635 | RSS: Main: 309530624 10:04:57 INFO - PROCESS | 1635 | 10:08:00 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-yui.html (next: http://localhost:49252/tests/dromaeo/cssquery-dojo.html) 10:08:01 INFO - PROCESS | 1635 | RSS: Main: 359821312 10:08:01 INFO - PROCESS | 1635 | 10:08:01 INFO - PROCESS | 1635 | __start_tp_report 10:08:01 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 10:08:01 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 10:08:01 INFO - PROCESS | 1635 | |i|pagename|runs| 10:08:01 INFO - PROCESS | 1635 | |0;dojo.html;3306.6693330666935;3318.8384065577047;3328.0515053209833;3350.430426827439;3359.2945481448896;5601.3558440779325;5652.265205523287;5685.9147112793235;5692.459216374443;5707.429257074301;5590.329160500735;5600.803971860975;5676.3188417389865;5684.2326285951385;5696.11710184922;5134.306868572751;5145.74271286436;5171.870703232412;5189.792408303672;5208.374995000605;4338.17574660815;5102.6428150029515;5131.358580177478;5140.563052140554;5251.028559716446;4480.775961201944;4956.826511072113;5100.132977393852;5155.793768249266;5178.7669554869935;5163.173892177234;5168.3539557555305;5221.190715439113;5226.947730522722;5279.63042587019;4079.2861249281555;5138.820141295074;5163.509466600667;5180.1452760294505;5189.987952349294;5542.584306177013;5568.52667523257;5580.832575022756;5590.748416321276;5604.103343465067;6088.1476593276975;6111.388861113853;6118.4799292060225;6177.196964394645;6182;6271.247450305947;6305.747770089191;6310.053491976194;6313.621182729005;6314.116023756677;5629.3526244482;5671.234383358276;5688.544916406678;5731.914021289684;5744.425557444222;6288.465480434123;6314.052892066226;6324.272708638474;6343.714532846033;6354.523410744213;6087.908681369783;6096.756129754849;6097.725602347905;6105.206323177958;6111.35830737775;6073.6659483727935;6115.240341084264;6119.2350956130485;6124.724387402578;6158.907616385758;6056.182415373956;6106.786754545499;6141.293751218667;6158.168647232566;6189.81430557084;6071.241094863142;6180.6600636965395;6183.474404675563;6202.41077097675;6228.813135605939;4841.709497430165;4899.412070551557;4901.9019619608125;4907.533784290486;4919.745464906426;4337.978310108492;4365.80353884076;4379.890502737457;4379.9781001095425;4403.911921761547;5720.42795720433;5727.455891690366;5744.856378590485;5747.195392645033;5762.423757624204;6144.477719393813;6262.655553944577;6264.310925798159;6266.216722909637;6281;5591.10542313228;5592.496675299242;5610.943890561042;5738.799142030009;5755;5302.2046692996355;5333.093374126408;5356.303680521585;5378;5402.135658294654;6263.655498947602;6270.780522681593;6283.403076707706;6292.842678933063;6301.873962520815;4689.577937985598;6272;6279.403456671792;6287.748490060347;6289.811305660837;6126.700892189639;6182.7217775201125;6202.100695399232;6212.471939884979;6264.59280146789;6127.3566275540425;6143.262808463013;6150.415710507495;6160.7535698571555;6169.136320915165;4638.814447422065;6197.287311959182;6208.037754148114;6218.875622487434;6237.844053898689;4904.583110435653;5820.592558520862;5891.852703682443;5946.881062378815;5966.3138739043825;5965.28416590012;6020.337762845996;6033;6050.2437195350585;6077.088436734349;5871.6770577618645;5881.794137205177;5882.617629854046;5902.2622172228475;5907.940920590911;5772.7690892363835;5877.177195192591;5929.52563794904;6020.578559500793;6043.425874541911;5790.305163380422;5885.323187833283;5890.352061273171;5893.086571581412;5909.409059094056 10:08:01 INFO - PROCESS | 1635 | |1;ext.html;481.7543053042949;486.49404619196036;509.43452767428147;514.6731825290941;527.599024741197;29375.853120734395;29917.850410747942;30745.99999999997;37840.62159378405;39311.21377572447;40718.18563628726;41204.38193427101;41307;41341.7932910335;41578;9227.907720922789;9371.812563748721;9373.578188981512;9376.203022743075;9390.62437502499;8317.500949942993;8535.274501667365;8539.786505337384;9263.351565390425;9350.906490935105;8440.535770532619;8518.87221691675;10981.341119532854;11241.325520468758;11249.83125253122;11120.499577519031;11172.720681982933;11184.32894026357;11187.105031597534;11187.552497900075;11140.164487663376;11175.385353805537;11207.271527350696;11221.943890280496;11227.101831853513;38709.419358709645;39009.41485877714;39066;39150.41274380887;39157.02107447308;3160.5891234139644;3249.8375081245845;3295.555100061509;3506.912327191815;3800.5646269641525;28442.004529841357;28516.287092822637;29841.403171936658;30001.099967001024;30569.847150764326;3184.538241954927;3216.710496055366;3232.660570640096;3235.1912021994503;3310.6358300586917;492678;494920.5253973707;495395;495682;495930;32866.83566582152;33672.83163584166;58421;58935.115973260436;58984.70507647434;34331;34862.65137348594;34902.82548587241;34916.30167396638;34917.476237856456;14050.086744361584;14191;14215.36030878613;14232.928835355964;14301.141931484144;12060.457279422271;12120.878791211975;12130.696732581757;12153.453094610586;12154.756904861853;7152.678129484186;7322.96338518304;7649.38804895607;7668.769936901902;7703.653335599912;3031.030070377502;3131.248500359897;3209.3420848725955;3241.9837900810344;3245.448273793461;868.9391494327144;877.5392918717724;1062.9202809789297;1088.3252383522267;1103.4427614055012;28832.279193020342;29456.645785678113;29880.850595747317;29906.40187196244;29971;1015.6902144845745;1036.1762398892897;1087.445402844555;1103.8068338040812;1110.777844431117;15225.390984360723;15286;15370.385184592491;15515.767263491056;15767.49873251629;648823;656031;656492;656509;657513;47876.28185577219;55815.720921395136;56264.71867640636;56299;56440.717796411576;1198.2211562484456;1212.4725744301081;1256.5350820196586;1330.215173047887;1334.5395838435725;27263.454730904876;27429.217100888378;30459.2385190372;30720.539191912587;30902.227444313172;48943.26585101155;49433.25850112323;49462.258066129754;49483.25775113448;49514.50485495099;534485;538184;538926;539358;659782;39930.60069399269;40272.597274026884;40510.797446012584;40511.59488405196;40871;44642.107157856015;44742;45025;45041.774791125834;45413.63759087233;36948.26103477862;37137.814310928276;41110.18906407773;41200.793996029824;41499.60505134313;37912.829588744666;38690.80654596709;38714.22571548497;40955;42258.7887060575;37840.62159378371;39625.603743963344;39742;39746;40094.39858402068;40037.599624003386;40261.59738402579;40364.3945340826;40397.78806635806;40436.191276173726;31219.37561248808;31248.843755781076;31496.84251578819;31515.84242078775;32207.194820129684 10:08:01 INFO - PROCESS | 1635 | |2;jquery.html;350.23124242288185;367.6801182970816;371.8085186129142;382.39008780994305;400.105763618313;14516;14730.116193028412;15296.31166597503;16556.586085347873;19521.902390488063;18958.620827583476;20019.299324523643;20185.899070504664;20287;20439.795602043974;5599.608027438071;5913.763449462016;5916.674582897939;5920.792772252972;5927.585069045169;5540.030494663418;5800.53595712342;5841.853953651172;5863.79476718315;5872.882542349151;5573.359063707665;5817.796377126801;5831.620944638585;5891.440313170242;5919.408059194089;5933.525317974551;5949.583529152961;5972.641641501524;5977.551683623702;5980.970095149518;5504.697241651707;5652.491275785158;5742.971285143548;5743.597948143632;5754.4533269339345;13045.34773261333;13083.803742943863;13090.803637945439;13283;13284.40220190084;2177.3359125466727;2217.6673498975283;2224.8887555622314;2317.304808557426;2464.445499762557;10174.931632178588;10177.287589868713;10363.844542331872;10473.528691208838;13261.872740817042;1729.8010728766228;1759.6128851652616;1783.3669047488215;1790.9462716118537;1840.3926704187586;114568;121538;122036;123449.38275308655;124310;11286.887131128748;11468.025217856575;11545.365004924643;11558.30650160993;11784.94107529474;14576.635584110481;14605.926970365294;14636.341364638616;14640.633984150269;14644.707105857824;8122.634481448349;8397.202265784741;8407.495550267004;8594;8597.355198360148;6941.861162776817;8253.79365515867;8291.046529649167;8292.17078292178;8468.872966905501;4346.717463364871;4439.334099885043;4469.910601787946;4521.276595744665;4528.954710452919;1839.9880065963666;1896.9620607587772;1898.3260942365537;1900.828925396721;1901.4580844459263;789.6801795272924;854.8587635506668;897.4660077254024;933.9112784285481;944.5135755086136;20277.594448110955;20912.47718807042;20985.475363116046;21315;21473.785262147492;928.3455164109287;929.1683942871227;949.4350861237552;966.5022513405602;969.3795970578835;10745.355278683304;11096.944515277533;11135.387553684463;11149.665510034712;11189.384583847805;589523;594798;600212;614493;624947;89649.55175224213;95232;95721.52139239259;128059;141422;880.6433394475248;913.8263729891432;992.3648864726581;992.692265397729;993.9602415903283;17611.29554817844;18065.2773889043;18626.720599191292;18804.835786014904;18950.905245473685;54706.45293547173;55003.295583917745;55143.72428137834;56075.91976073557;57907.71046144742;79886.2011379902;80794;81525.59237203776;82715.58642206991;83951.16048839678;6992.965035174791;7647.235276472308;7756.68973241064;7887.526748395115;8171.5505647189975;8986.68546600866;10517.15862730995;11470.254433461798;12264.570740024057;13012.47950082024;10879.347239165676;10996.505157268102;11072.501737421997;11357.829632555684;11429.828552571551;10681.732956676145;10814.243002989715;10992.450377481255;11136;11266.436678166223;11215.327080375204;11223.88776112261;11268.7746245073;11502;11560.47977841016;7932.325752310888;8033.076196237507;8073.515589064675;8461.153884611489;8484.872726909225;8291.502509849428;10481.685549433529;11023.779524409307;11326;11386 10:08:01 INFO - PROCESS | 1635 | |3;mootools.html;1226.5277868020812;1437.8346490153633;1554.7590123530856;1780.741792440097;1814.5191524246077;7947.483413578113;8386.790330241733;8397.832043359136;8403.285720713746;8403.873941890877;7290.817729556764;7581.2039735827775;7586.544807311565;7876.369890408768;8094.838103237932;2312.5721741477796;2347.7182738071456;2347.9647805282934;2351.5179388225374;2355.9293221203307;2328.8835558222104;2341.625339945609;2373.3947843299934;2373.667686523888;2377.3699969508093;2320.8607483550954;2325.1745630301252;2348.154664320843;2350.5063936573342;2351.5532048910736;2313.6298192289323;2320.2807129789735;2342.7071616047992;2343.824213184001;2344.6600242964673;2317.953640927189;2340.4850932794757;2346.378209774411;2348.7533808950157;2350.7766762157567;7319.5242309249725;8399.11809260031;8410.537420441873;8412.411131220817;8437.071922088566;5031.295618613397;5056.6713163644245;5220.4779522047875;5223.242629818693;5223.347081614798;3144.9056528304186;3348.8493017814258;3392.6607339266125;3407.522946787452;3456.5333679953137;1006.8791744990575;1035.8705161854768;1083.8482612434266;1091.0725882999466;1101.6529793115224;10665.893341066645;10764.569417223302;10767.192460565411;10768.192385571036;10772.353658780497;4474.440694913136;4543.38664280318;4625.121226966933;4631.513691062457;4736.313234580966;9324.673636422693;9333;9333.30000249984;9344.439333640003;9355.391899526508;5189.4032186299055;5192.662476938987;5194.740262986836;5202.739863006835;5202.843914682566;4226.619604235633;4357.67317451186;4451.376807246988;4732.858014259577;4759.23852183649;3757.9060523487133;3795.7532760370486;3852.8651497198025;3874.341361968472;3898.590647981978;1233.9444724987397;1265.1397050006085;1266.0947422592706;1267.8415198100238;1277.9041571882146;800.8518424091561;875.7066382761719;903.2864037410503;921.8755468011733;924.5654542365078;5652.660840349592;5795.681237531976;5870.765169393262;6023.728932198062;6127.509799216052;832.5421018439833;897.708244820436;911.5077857956762;931.4411353188168;975.658519518163;3434.639362866913;3840.30874442603;3899.74651647642;3904.7852368119457;3953.920921581552;8551.957240213758;9646.710598682052;10216.489175541343;10229.693109206735;10234.976502349706;10159.644412445678;10161.288709790244;10173;10187.694369168938;10195.929427410163;7345.449091318173;7736.7678969631;7772.494787838772;7915.525068495908;7918.802029949297;4795.544423279783;5731.598788084794;5953.196318497121;5968.552358573212;6021.458068773832;9013.414128081653;9099.681511147079;9182.311326650395;9188.448693078437;9286.72139835806;7913.683452661829;9239.72280831576;9418.670346537838;9527.285453591147;9548.236141108833;6508.08886755864;6700.6984685688285;6720.227173875067;7034.261402552863;7166.749163779243;7495.475316727777;7893.052833659768;7899.881501777593;7900.051993760785;7995.400344974267;7143.964280178566;7208.387287080657;7375.594342311224;7377.77866664001;7416.592087435027;7099.858002840017;7218.747343842941;7287.234840341688;7299.963500182465;7309;6425.58233714807;6652.73389064432;6654.101696270942;6657.06801047844;6670.366315200243;5004.124278251219;5011.09800235961;5033.0688822567945;5062.924056139087;5081.974590127025;4784.784684689128;5022.518357084596;5083.72039537829;5177.145770947752;5207.843764687065 10:08:01 INFO - PROCESS | 1635 | |4;prototype.html;300.58519243444044;303.9073082709774;310.18266866805976;313.47962382445144;317.43179708322106;4261.446012018437;5146.228065790134;5188;5209.426963034063;5249.632525723201;4392.538783427742;4722.480527142012;4876.853694389165;5051.924221136685;5179.8762507371985;3952.6837852971835;3991.501062367197;4012.435150291374;4014.658754005913;4016.2689371227552;3974.304496713078;3983.382575700771;4011.4154908811;4012.6589239914642;4028.7582745035247;3849.961500384988;3923;3959.5644479107277;3985.60143985602;3994.980025099885;3643.325984692827;3959.8614048508166;3968.4840970673627;3988.8061566138654;3993.940090898639;3458.4639380896;3487.843047062888;3492.4761285807076;3496.965030349689;3511.20997775501;4282.743035417854;4957;4959.727215003173;4979.862686507513;4991.251312303184;3770.377887648562;3984.561698213194;4292.892677683051;4446.199684056868;4530.1392735380505;4619.930701039487;5273.839755253838;5407.594430417735;5433.483819037185;5435.374931882845;2883.135059482147;3241.2545114623767;3678.1356381250384;3802.8098595070137;3840.481534992768;4759.381280433521;5320.6275560710765;5444;5631.211630371751;5643.520300774439;3261.445554255782;3472.6977383481194;3629.8366573503727;3891.1439483314193;3896.883093507199;3369.882054128094;3452.896413107611;3643.506162473373;3901.804909754501;3960.405939109099;3523;4506.662000349921;4571.497135315112;4574.81700731967;4575.633949284049;4666.803239780761;5371.301730774974;5426.9185962210595;5471.644343117685;5472.616916815864;1104.7238190452388;1278.3317770309834;1279.8528169260653;1290.7934730443085;1292.5605294200018;762.7940456076828;794.9705131854147;833.7993289662987;854.0221446443787;859.1709000814119;4894.412670479565;4895.485973972682;5128.717920514333;5146.919146979101;5232.607554433433;4514.133525211449;5177.197534382177;5552.305961754781;5560.860978475571;5564.916526252109;3887.8639247626197;4914.8034078637165;5011.972545628138;5103.540681338697;5122.513361230677;2141.785821417877;2544.7328030556896;2588.7799537039186;2764.6129541864157;2804.8036637435594;4685.361492122774;4731;5509.3664228613425;5510.779568817203;5563.519932866124;4558.632410276904;4842.457709702435;4897.877553061131;5470.362209130963;5477.931803298318;4870.537298956593;5476.91784623231;5501.752421141058;5503.669779813224;5508.834734957958;5267.920981185362;5292.285541451854;5295.920561191509;5325.467453254643;5327.547158491571;4909.337239472626;5162.948370516397;5592.273004509388;5625.718714064198;5628.887422251614;4823.614110871052;5791.391903850035;5846.56150788684;5857.26784151981;5869.148973398748;3550.680438760524;3776.66010059085;3810.7713537187856;3811.7903515305816;3814.8092595369567;4396.450443694538;4604.5165257648805;5518.7373176997235;5628.887422251614;5641.210230567806;4515.706479078849;5469.425710300361;5494.928488944617;5498.158117030839;5508.752106155152;5244.921326180034;5430.479465749597;5486.670799752028;5512.6692398456225;5515.531179849758;4361.2367835629275;4643.769401108641;5381.650192737459;5391.75737091824;5394.379646340721;4439.689221754446;4934.013197360614;5172.405173404956;5290.9735451322495;5317.335333083364;4888.584470320063;5145.562627176732;5370.382406023201;5383.7308134593895;5418.241446197614 10:08:01 INFO - PROCESS | 1635 | |5;yui.html;692.8025512728873;696.2132789947362;766.995236240525;778.2412148155546;803.3533005930226;14360.569182924502;14753.557393278192;14772.261386930664;14782.113073215614;14794.482193123244;12857.357132143403;12991.155574887625;12991.545295914644;12995.090343675924;13009.219446833196;636.350922059499;652.0935899100255;653.5947712418307;778.5640041576721;780.4068907630185;803.2248879830962;804.7424824056304;816.0656048824093;816.2001238785964;817.8037271054964;849.5242664108089;850.1371108325055;850.2517784349764;850.3537311643134;852.2755657690944;657.1424291299852;672.3008071605525;846.2172490446312;847.7838151271447;848.2281124177;610.9755609775603;627.4447114303874;628.0140179917531;628.5882746800853;629.2511910826103;13449.66375840602;13467.259300738455;13478.39347229377;13500.257485838274;13554;12455.688607784787;12456.75086498265;12592;12656;12734.61796146117;11896.940515297454;11918.940405298004;11925.88074119265;12011.339376334297;12062.577809776616;4107;4109.219248342805;4183.91632167355;4327.415798867176;4353.368761529592;83396;87118.56440717819;87823;89856.10143898608;90521;9991.650292239881;10158.339707918962;10233.897661023295;10415.010573995458;10511.737206569744;18591.721124183146;19120.948347840724;19235.4229373119;19268.614627707368;19353.612927741367;7759.495632783851;7819.452638315377;7844.176361482077;7941.36469082472;7946.801329966682;4180.080382315886;4232.830686772495;4370;4430.911381772346;4454.28731402974;3381.205416727067;3498.2653642734804;3505.859765609398;3506.01831487184;3513.0690367052753;655.3119224813927;655.6295692933477;660.8513084555937;666.1140682891743;675.8682056998838;524.3917056214773;572.2532095615262;629.8929182039064;660.2638058564665;667.1393901866538;7798;8986.775330616787;9084.083710800998;9212.815743685089;9297.209737172416;561.9185218143347;605.198112500938;648.8604949935786;655.2039272284197;682.3858527325447;4055.6958228133008;4240.618344349024;4321.75013021801;4515.128314756464;4591.79336929839;68860.31139688539;71078;73766.8934965976;110479;111281;95379;95382.52308738412;99708;105395;107584;86832;89128;90047;90451.54774226087;90683.54658226931;7787.844243115219;8512.957435213031;8781.990071141901;8823.911760882565;9069.546522673973;57014.714926425106;57066.7146664264;57213.42786572248;57699;58372.70813645905;69949;79418;81087.59456202682;83981;84808.57595711981;12001.579944702244;12003.339816310187;12380.4428800706;12519.123661343983;12584.119111662096;13330.26683532415;14337.713245734818;14443.78334324965;15035;15069.09585424878;11213.383263920236;12893.097483176087;12939.482420703067;13402.93298533501;13784.586462406143;11964;13110.672233194246;13240.53658121961;13266.137701049402;13628;12889.355532223539;12965.15726477776;12966;13171.670708231988;13248.735025299633;10876.673699789018;11263.718407039889;11421.657350279505;11533.192676512563;11727.824082638937;9919.801603968024;11349.262297950607;11407.25852819564;11495.654520647939;11504.942475287904 10:08:01 INFO - PROCESS | 1635 | __end_tp_report 10:08:01 INFO - PROCESS | 1635 | __start_cc_report 10:08:01 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,1668 10:08:01 INFO - PROCESS | 1635 | __end_cc_report 10:08:01 INFO - PROCESS | 1635 | __startTimestamp1484330881008__endTimestamp 10:08:01 INFO - PROCESS | 1635 | 10:08:01 INFO - PROCESS | 1635 | ------- Summary: start ------- 10:08:01 INFO - PROCESS | 1635 | Number of tests: 6 10:08:01 INFO - PROCESS | 1635 | 10:08:01 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5657.28 Median:5877.18 stddev:669.19 (11.4%) stddev-sans-first:645.33 10:08:01 INFO - PROCESS | 1635 | Values: 3306.7 3318.8 3328.1 3350.4 3359.3 5601.4 5652.3 5685.9 5692.5 5707.4 5590.3 5600.8 5676.3 5684.2 5696.1 5134.3 5145.7 5171.9 5189.8 5208.4 4338.2 5102.6 5131.4 5140.6 5251.0 4480.8 4956.8 5100.1 5155.8 5178.8 5163.2 5168.4 5221.2 5226.9 5279.6 4079.3 5138.8 5163.5 5180.1 5190.0 5542.6 5568.5 5580.8 5590.7 5604.1 6088.1 6111.4 6118.5 6177.2 6182.0 6271.2 6305.7 6310.1 6313.6 6314.1 5629.4 5671.2 5688.5 5731.9 5744.4 6288.5 6314.1 6324.3 6343.7 6354.5 6087.9 6096.8 6097.7 6105.2 6111.4 6073.7 6115.2 6119.2 6124.7 6158.9 6056.2 6106.8 6141.3 6158.2 6189.8 6071.2 6180.7 6183.5 6202.4 6228.8 4841.7 4899.4 4901.9 4907.5 4919.7 4338.0 4365.8 4379.9 4380.0 4403.9 5720.4 5727.5 5744.9 5747.2 5762.4 6144.5 6262.7 6264.3 6266.2 6281.0 5591.1 5592.5 5610.9 5738.8 5755.0 5302.2 5333.1 5356.3 5378.0 5402.1 6263.7 6270.8 6283.4 6292.8 6301.9 4689.6 6272.0 6279.4 6287.7 6289.8 6126.7 6182.7 6202.1 6212.5 6264.6 6127.4 6143.3 6150.4 6160.8 6169.1 4638.8 6197.3 6208.0 6218.9 6237.8 4904.6 5820.6 5891.9 5946.9 5966.3 5965.3 6020.3 6033.0 6050.2 6077.1 5871.7 5881.8 5882.6 5902.3 5907.9 5772.8 5877.2 5929.5 6020.6 6043.4 5790.3 5885.3 5890.4 5893.1 5909.4 10:08:01 INFO - PROCESS | 1635 | 10:08:01 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:69193.54 Median:21245.69 stddev:153943.64 (724.6%) stddev-sans-first:154289.05 10:08:01 INFO - PROCESS | 1635 | Values: 481.8 486.5 509.4 514.7 527.6 29375.9 29917.9 30746.0 37840.6 39311.2 40718.2 41204.4 41307.0 41341.8 41578.0 9227.9 9371.8 9373.6 9376.2 9390.6 8317.5 8535.3 8539.8 9263.4 9350.9 8440.5 8518.9 10981.3 11241.3 11249.8 11120.5 11172.7 11184.3 11187.1 11187.6 11140.2 11175.4 11207.3 11221.9 11227.1 38709.4 39009.4 39066.0 39150.4 39157.0 3160.6 3249.8 3295.6 3506.9 3800.6 28442.0 28516.3 29841.4 30001.1 30569.8 3184.5 3216.7 3232.7 3235.2 3310.6 492678.0 494920.5 495395.0 495682.0 495930.0 32866.8 33672.8 58421.0 58935.1 58984.7 34331.0 34862.7 34902.8 34916.3 34917.5 14050.1 14191.0 14215.4 14232.9 14301.1 12060.5 12120.9 12130.7 12153.5 12154.8 7152.7 7323.0 7649.4 7668.8 7703.7 3031.0 3131.2 3209.3 3242.0 3245.4 868.9 877.5 1062.9 1088.3 1103.4 28832.3 29456.6 29880.9 29906.4 29971.0 1015.7 1036.2 1087.4 1103.8 1110.8 15225.4 15286.0 15370.4 15515.8 15767.5 648823.0 656031.0 656492.0 656509.0 657513.0 47876.3 55815.7 56264.7 56299.0 56440.7 1198.2 1212.5 1256.5 1330.2 1334.5 27263.5 27429.2 30459.2 30720.5 30902.2 48943.3 49433.3 49462.3 49483.3 49514.5 534485.0 538184.0 538926.0 539358.0 659782.0 39930.6 40272.6 40510.8 40511.6 40871.0 44642.1 44742.0 45025.0 45041.8 45413.6 36948.3 37137.8 41110.2 41200.8 41499.6 37912.8 38690.8 38714.2 40955.0 42258.8 37840.6 39625.6 39742.0 39746.0 40094.4 40037.6 40261.6 40364.4 40397.8 40436.2 31219.4 31248.8 31496.8 31515.8 32207.2 10:08:01 INFO - PROCESS | 1635 | 10:08:01 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:34459.85 Median:358.96 stddev:100767.22 (28072.3%) stddev-sans-first:101017.35 10:08:01 INFO - PROCESS | 1635 | Values: 350.2 367.7 371.8 382.4 400.1 14516.0 14730.1 15296.3 16556.6 19521.9 18958.6 20019.3 20185.9 20287.0 20439.8 5599.6 5913.8 5916.7 5920.8 5927.6 5540.0 5800.5 5841.9 5863.8 5872.9 5573.4 5817.8 5831.6 5891.4 5919.4 5933.5 5949.6 5972.6 5977.6 5981.0 5504.7 5652.5 5743.0 5743.6 5754.5 13045.3 13083.8 13090.8 13283.0 13284.4 2177.3 2217.7 2224.9 2317.3 2464.4 10174.9 10177.3 10363.8 10473.5 13261.9 1729.8 1759.6 1783.4 1790.9 1840.4 114568.0 121538.0 122036.0 123449.4 124310.0 11286.9 11468.0 11545.4 11558.3 11784.9 14576.6 14605.9 14636.3 14640.6 14644.7 8122.6 8397.2 8407.5 8594.0 8597.4 6941.9 8253.8 8291.0 8292.2 8468.9 4346.7 4439.3 4469.9 4521.3 4529.0 1840.0 1897.0 1898.3 1900.8 1901.5 789.7 854.9 897.5 933.9 944.5 20277.6 20912.5 20985.5 21315.0 21473.8 928.3 929.2 949.4 966.5 969.4 10745.4 11096.9 11135.4 11149.7 11189.4 589523.0 594798.0 600212.0 614493.0 624947.0 89649.6 95232.0 95721.5 128059.0 141422.0 880.6 913.8 992.4 992.7 994.0 17611.3 18065.3 18626.7 18804.8 18950.9 54706.5 55003.3 55143.7 56075.9 57907.7 79886.2 80794.0 81525.6 82715.6 83951.2 6993.0 7647.2 7756.7 7887.5 8171.6 8986.7 10517.2 11470.3 12264.6 13012.5 10879.3 10996.5 11072.5 11357.8 11429.8 10681.7 10814.2 10992.5 11136.0 11266.4 11215.3 11223.9 11268.8 11502.0 11560.5 7932.3 8033.1 8073.5 8461.2 8484.9 8291.5 10481.7 11023.8 11326.0 11386.0 10:08:01 INFO - PROCESS | 1635 | 10:08:01 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5433.32 Median:5191.03 stddev:2934.95 (56.5%) stddev-sans-first:2926.15 10:08:01 INFO - PROCESS | 1635 | Values: 1226.5 1437.8 1554.8 1780.7 1814.5 7947.5 8386.8 8397.8 8403.3 8403.9 7290.8 7581.2 7586.5 7876.4 8094.8 2312.6 2347.7 2348.0 2351.5 2355.9 2328.9 2341.6 2373.4 2373.7 2377.4 2320.9 2325.2 2348.2 2350.5 2351.6 2313.6 2320.3 2342.7 2343.8 2344.7 2318.0 2340.5 2346.4 2348.8 2350.8 7319.5 8399.1 8410.5 8412.4 8437.1 5031.3 5056.7 5220.5 5223.2 5223.3 3144.9 3348.8 3392.7 3407.5 3456.5 1006.9 1035.9 1083.8 1091.1 1101.7 10665.9 10764.6 10767.2 10768.2 10772.4 4474.4 4543.4 4625.1 4631.5 4736.3 9324.7 9333.0 9333.3 9344.4 9355.4 5189.4 5192.7 5194.7 5202.7 5202.8 4226.6 4357.7 4451.4 4732.9 4759.2 3757.9 3795.8 3852.9 3874.3 3898.6 1233.9 1265.1 1266.1 1267.8 1277.9 800.9 875.7 903.3 921.9 924.6 5652.7 5795.7 5870.8 6023.7 6127.5 832.5 897.7 911.5 931.4 975.7 3434.6 3840.3 3899.7 3904.8 3953.9 8552.0 9646.7 10216.5 10229.7 10235.0 10159.6 10161.3 10173.0 10187.7 10195.9 7345.4 7736.8 7772.5 7915.5 7918.8 4795.5 5731.6 5953.2 5968.6 6021.5 9013.4 9099.7 9182.3 9188.4 9286.7 7913.7 9239.7 9418.7 9527.3 9548.2 6508.1 6700.7 6720.2 7034.3 7166.7 7495.5 7893.1 7899.9 7900.1 7995.4 7144.0 7208.4 7375.6 7377.8 7416.6 7099.9 7218.7 7287.2 7300.0 7309.0 6425.6 6652.7 6654.1 6657.1 6670.4 5004.1 5011.1 5033.1 5062.9 5082.0 4784.8 5022.5 5083.7 5177.1 5207.8 10:08:01 INFO - PROCESS | 1635 | 10:08:01 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4339.90 Median:4873.70 stddev:1332.09 (27.3%) stddev-sans-first:1300.87 10:08:01 INFO - PROCESS | 1635 | Values: 300.6 303.9 310.2 313.5 317.4 4261.4 5146.2 5188.0 5209.4 5249.6 4392.5 4722.5 4876.9 5051.9 5179.9 3952.7 3991.5 4012.4 4014.7 4016.3 3974.3 3983.4 4011.4 4012.7 4028.8 3850.0 3923.0 3959.6 3985.6 3995.0 3643.3 3959.9 3968.5 3988.8 3993.9 3458.5 3487.8 3492.5 3497.0 3511.2 4282.7 4957.0 4959.7 4979.9 4991.3 3770.4 3984.6 4292.9 4446.2 4530.1 4619.9 5273.8 5407.6 5433.5 5435.4 2883.1 3241.3 3678.1 3802.8 3840.5 4759.4 5320.6 5444.0 5631.2 5643.5 3261.4 3472.7 3629.8 3891.1 3896.9 3369.9 3452.9 3643.5 3901.8 3960.4 3523.0 4506.7 4571.5 4574.8 4575.6 4666.8 5371.3 5426.9 5471.6 5472.6 1104.7 1278.3 1279.9 1290.8 1292.6 762.8 795.0 833.8 854.0 859.2 4894.4 4895.5 5128.7 5146.9 5232.6 4514.1 5177.2 5552.3 5560.9 5564.9 3887.9 4914.8 5012.0 5103.5 5122.5 2141.8 2544.7 2588.8 2764.6 2804.8 4685.4 4731.0 5509.4 5510.8 5563.5 4558.6 4842.5 4897.9 5470.4 5477.9 4870.5 5476.9 5501.8 5503.7 5508.8 5267.9 5292.3 5295.9 5325.5 5327.5 4909.3 5162.9 5592.3 5625.7 5628.9 4823.6 5791.4 5846.6 5857.3 5869.1 3550.7 3776.7 3810.8 3811.8 3814.8 4396.5 4604.5 5518.7 5628.9 5641.2 4515.7 5469.4 5494.9 5498.2 5508.8 5244.9 5430.5 5486.7 5512.7 5515.5 4361.2 4643.8 5381.7 5391.8 5394.4 4439.7 4934.0 5172.4 5291.0 5317.3 4888.6 5145.6 5370.4 5383.7 5418.2 10:08:01 INFO - PROCESS | 1635 | 10:08:01 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:20379.05 Median:4553.46 stddev:29585.42 (649.7%) stddev-sans-first:29631.49 10:08:01 INFO - PROCESS | 1635 | Values: 692.8 696.2 767.0 778.2 803.4 14360.6 14753.6 14772.3 14782.1 14794.5 12857.4 12991.2 12991.5 12995.1 13009.2 636.4 652.1 653.6 778.6 780.4 803.2 804.7 816.1 816.2 817.8 849.5 850.1 850.3 850.4 852.3 657.1 672.3 846.2 847.8 848.2 611.0 627.4 628.0 628.6 629.3 13449.7 13467.3 13478.4 13500.3 13554.0 12455.7 12456.8 12592.0 12656.0 12734.6 11896.9 11918.9 11925.9 12011.3 12062.6 4107.0 4109.2 4183.9 4327.4 4353.4 83396.0 87118.6 87823.0 89856.1 90521.0 9991.7 10158.3 10233.9 10415.0 10511.7 18591.7 19120.9 19235.4 19268.6 19353.6 7759.5 7819.5 7844.2 7941.4 7946.8 4180.1 4232.8 4370.0 4430.9 4454.3 3381.2 3498.3 3505.9 3506.0 3513.1 655.3 655.6 660.9 666.1 675.9 524.4 572.3 629.9 660.3 667.1 7798.0 8986.8 9084.1 9212.8 9297.2 561.9 605.2 648.9 655.2 682.4 4055.7 4240.6 4321.8 4515.1 4591.8 68860.3 71078.0 73766.9 110479.0 111281.0 95379.0 95382.5 99708.0 105395.0 107584.0 86832.0 89128.0 90047.0 90451.5 90683.5 7787.8 8513.0 8782.0 8823.9 9069.5 57014.7 57066.7 57213.4 57699.0 58372.7 69949.0 79418.0 81087.6 83981.0 84808.6 12001.6 12003.3 12380.4 12519.1 12584.1 13330.3 14337.7 14443.8 15035.0 15069.1 11213.4 12893.1 12939.5 13402.9 13784.6 11964.0 13110.7 13240.5 13266.1 13628.0 12889.4 12965.2 12966.0 13171.7 13248.7 10876.7 11263.7 11421.7 11533.2 11727.8 9919.8 11349.3 11407.3 11495.7 11504.9 10:08:01 INFO - PROCESS | 1635 | -------- Summary: end -------- 10:08:01 INFO - PROCESS | 1635 | 10:08:01 INFO - TEST-INFO | 1635: exit 0 10:08:01 INFO - TEST-OK | dromaeo_css | took 1118874ms 10:08:01 INFO - TEST-START | kraken 10:08:01 INFO - operating with platform_type : mac_ 10:08:01 INFO - Initialising browser for kraken test... 10:08:01 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpm_vsfC/profile http://localhost:49252/getInfo.html) 10:08:04 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 10:08:04 INFO - PROCESS | 1644 | colorDepth:24 10:08:04 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 10:08:04 INFO - PROCESS | 1644 | __metrics 10:08:04 INFO - PROCESS | 1644 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:08:04 INFO - PROCESS | 1644 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:08:05 INFO - TEST-INFO | 1644: exit 0 10:08:05 INFO - Browser initialized. 10:08:05 INFO - Running cycle 1/1 for kraken test... 10:08:05 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpm_vsfC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:08:17 INFO - PROCESS | 1645 | RSS: Main: 222339072 10:08:17 INFO - PROCESS | 1645 | 10:08:20 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-beat-detection) 10:08:21 INFO - PROCESS | 1645 | RSS: Main: 272596992 10:08:21 INFO - PROCESS | 1645 | 10:08:23 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-dft) 10:08:24 INFO - PROCESS | 1645 | RSS: Main: 283410432 10:08:24 INFO - PROCESS | 1645 | 10:08:27 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-fft) 10:08:28 INFO - PROCESS | 1645 | RSS: Main: 381382656 10:08:28 INFO - PROCESS | 1645 | 10:08:30 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-oscillator) 10:08:30 INFO - PROCESS | 1645 | RSS: Main: 283750400 10:08:30 INFO - PROCESS | 1645 | 10:08:32 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:08:33 INFO - PROCESS | 1645 | RSS: Main: 284987392 10:08:33 INFO - PROCESS | 1645 | 10:08:36 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-darkroom) 10:08:36 INFO - PROCESS | 1645 | RSS: Main: 326160384 10:08:36 INFO - PROCESS | 1645 | 10:08:39 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-desaturate) 10:08:40 INFO - PROCESS | 1645 | RSS: Main: 326414336 10:08:40 INFO - PROCESS | 1645 | 10:08:43 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49252/tests/kraken/driver.html?testName=json-parse-financial) 10:08:43 INFO - PROCESS | 1645 | RSS: Main: 325296128 10:08:43 INFO - PROCESS | 1645 | 10:08:45 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49252/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:08:45 INFO - PROCESS | 1645 | RSS: Main: 299479040 10:08:45 INFO - PROCESS | 1645 | 10:08:47 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:08:48 INFO - PROCESS | 1645 | RSS: Main: 303792128 10:08:48 INFO - PROCESS | 1645 | 10:08:50 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:08:51 INFO - PROCESS | 1645 | RSS: Main: 305975296 10:08:51 INFO - PROCESS | 1645 | 10:08:54 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:08:54 INFO - PROCESS | 1645 | RSS: Main: 316411904 10:08:54 INFO - PROCESS | 1645 | 10:08:57 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:08:58 INFO - PROCESS | 1645 | RSS: Main: 316198912 10:08:58 INFO - PROCESS | 1645 | 10:09:00 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49252/tests/kraken/driver.html?testName=ai-astar) 10:09:00 INFO - PROCESS | 1645 | RSS: Main: 359976960 10:09:00 INFO - PROCESS | 1645 | 10:09:00 INFO - PROCESS | 1645 | __start_tp_report 10:09:00 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 10:09:00 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 10:09:00 INFO - PROCESS | 1645 | |i|pagename|runs| 10:09:00 INFO - PROCESS | 1645 | |0;ai-astar;117;116;116;115;115;114;115;114;113;115 10:09:00 INFO - PROCESS | 1645 | |1;audio-beat-detection;149;112;111;146;112;110;111;108;139;110 10:09:00 INFO - PROCESS | 1645 | |2;audio-dft;184;182;182;184;184;181;185;187;185;186 10:09:00 INFO - PROCESS | 1645 | |3;audio-fft;81;60;59;88;62;60;63;58;82;58 10:09:00 INFO - PROCESS | 1645 | |4;audio-oscillator;91;71;95;70;69;71;89;68;69;92 10:09:00 INFO - PROCESS | 1645 | |5;imaging-gaussian-blur;89;87;90;87;90;86;89;87;89;85 10:09:00 INFO - PROCESS | 1645 | |6;imaging-darkroom;108;100;97;96;97;111;133;97;96;97 10:09:00 INFO - PROCESS | 1645 | |7;imaging-desaturate;83;80;79;79;83;86;86;84;82;81 10:09:00 INFO - PROCESS | 1645 | |8;json-parse-financial;55;53;53;53;57;59;59;57;57;57 10:09:00 INFO - PROCESS | 1645 | |9;json-stringify-tinderbox;66;78;77;76;72;70;71;70;71;72 10:09:00 INFO - PROCESS | 1645 | |10;stanford-crypto-aes;85;85;82;85;83;86;83;85;87;89 10:09:00 INFO - PROCESS | 1645 | |11;stanford-crypto-ccm;143;148;145;143;139;141;143;143;181;155 10:09:00 INFO - PROCESS | 1645 | |12;stanford-crypto-pbkdf2;167;166;169;165;165;167;166;166;165;169 10:09:00 INFO - PROCESS | 1645 | |13;stanford-crypto-sha256-iterative;68;66;67;68;67;66;67;68;67;68 10:09:00 INFO - PROCESS | 1645 | __end_tp_report 10:09:00 INFO - PROCESS | 1645 | __start_cc_report 10:09:00 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,1962 10:09:00 INFO - PROCESS | 1645 | __end_cc_report 10:09:00 INFO - PROCESS | 1645 | __startTimestamp1484330940327__endTimestamp 10:09:00 INFO - PROCESS | 1645 | 10:09:00 INFO - PROCESS | 1645 | ------- Summary: start ------- 10:09:00 INFO - PROCESS | 1645 | Number of tests: 14 10:09:00 INFO - PROCESS | 1645 | 10:09:00 INFO - PROCESS | 1645 | [#0] ai-astar Cycles:10 Average:115.00 Median:115.00 stddev:1.15 (1.0%) stddev-sans-first:0.97 10:09:00 INFO - PROCESS | 1645 | Values: 117.0 116.0 116.0 115.0 115.0 114.0 115.0 114.0 113.0 115.0 10:09:00 INFO - PROCESS | 1645 | 10:09:00 INFO - PROCESS | 1645 | [#1] audio-beat-detection Cycles:10 Average:120.80 Median:112.00 stddev:16.69 (14.9%) stddev-sans-first:14.24 10:09:00 INFO - PROCESS | 1645 | Values: 149.0 112.0 111.0 146.0 112.0 110.0 111.0 108.0 139.0 110.0 10:09:00 INFO - PROCESS | 1645 | 10:09:00 INFO - PROCESS | 1645 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:1.89 (1.0%) stddev-sans-first:2.00 10:09:00 INFO - PROCESS | 1645 | Values: 184.0 182.0 182.0 184.0 184.0 181.0 185.0 187.0 185.0 186.0 10:09:00 INFO - PROCESS | 1645 | 10:09:00 INFO - PROCESS | 1645 | [#3] audio-fft Cycles:10 Average:67.10 Median:62.50 stddev:11.68 (18.7%) stddev-sans-first:11.25 10:09:00 INFO - PROCESS | 1645 | Values: 81.0 60.0 59.0 88.0 62.0 60.0 63.0 58.0 82.0 58.0 10:09:00 INFO - PROCESS | 1645 | 10:09:00 INFO - PROCESS | 1645 | [#4] audio-oscillator Cycles:10 Average:78.50 Median:80.00 stddev:11.53 (14.4%) stddev-sans-first:11.31 10:09:00 INFO - PROCESS | 1645 | Values: 91.0 71.0 95.0 70.0 69.0 71.0 89.0 68.0 69.0 92.0 10:09:00 INFO - PROCESS | 1645 | 10:09:00 INFO - PROCESS | 1645 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:89.00 stddev:1.73 (1.9%) stddev-sans-first:1.79 10:09:00 INFO - PROCESS | 1645 | Values: 89.0 87.0 90.0 87.0 90.0 86.0 89.0 87.0 89.0 85.0 10:09:00 INFO - PROCESS | 1645 | 10:09:00 INFO - PROCESS | 1645 | [#6] imaging-darkroom Cycles:10 Average:103.20 Median:96.50 stddev:11.74 (12.2%) stddev-sans-first:12.32 10:09:00 INFO - PROCESS | 1645 | Values: 108.0 100.0 97.0 96.0 97.0 111.0 133.0 97.0 96.0 97.0 10:09:00 INFO - PROCESS | 1645 | 10:09:00 INFO - PROCESS | 1645 | [#7] imaging-desaturate Cycles:10 Average:82.30 Median:83.00 stddev:2.58 (3.1%) stddev-sans-first:2.73 10:09:00 INFO - PROCESS | 1645 | Values: 83.0 80.0 79.0 79.0 83.0 86.0 86.0 84.0 82.0 81.0 10:09:00 INFO - PROCESS | 1645 | 10:09:00 INFO - PROCESS | 1645 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:57.00 stddev:2.36 (4.1%) stddev-sans-first:2.47 10:09:00 INFO - PROCESS | 1645 | Values: 55.0 53.0 53.0 53.0 57.0 59.0 59.0 57.0 57.0 57.0 10:09:00 INFO - PROCESS | 1645 | 10:09:00 INFO - PROCESS | 1645 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:72.00 stddev:3.68 (5.1%) stddev-sans-first:3.12 10:09:00 INFO - PROCESS | 1645 | Values: 66.0 78.0 77.0 76.0 72.0 70.0 71.0 70.0 71.0 72.0 10:09:00 INFO - PROCESS | 1645 | 10:09:00 INFO - PROCESS | 1645 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:85.00 stddev:2.05 (2.4%) stddev-sans-first:2.18 10:09:00 INFO - PROCESS | 1645 | Values: 85.0 85.0 82.0 85.0 83.0 86.0 83.0 85.0 87.0 89.0 10:09:00 INFO - PROCESS | 1645 | 10:09:00 INFO - PROCESS | 1645 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:144.00 stddev:12.37 (8.6%) stddev-sans-first:12.98 10:09:00 INFO - PROCESS | 1645 | Values: 143.0 148.0 145.0 143.0 139.0 141.0 143.0 143.0 181.0 155.0 10:09:00 INFO - PROCESS | 1645 | 10:09:00 INFO - PROCESS | 1645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.50 Median:166.50 stddev:1.51 (0.9%) stddev-sans-first:1.59 10:09:00 INFO - PROCESS | 1645 | Values: 167.0 166.0 169.0 165.0 165.0 167.0 166.0 166.0 165.0 169.0 10:09:00 INFO - PROCESS | 1645 | 10:09:00 INFO - PROCESS | 1645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:0.79 (1.2%) stddev-sans-first:0.78 10:09:00 INFO - PROCESS | 1645 | Values: 68.0 66.0 67.0 68.0 67.0 66.0 67.0 68.0 67.0 68.0 10:09:00 INFO - PROCESS | 1645 | -------- Summary: end -------- 10:09:00 INFO - PROCESS | 1645 | 10:09:01 INFO - TEST-INFO | 1645: exit 0 10:09:01 INFO - TEST-OK | kraken | took 59722ms 10:09:01 INFO - SUITE-END | took 1178s 10:09:01 INFO - Completed test suite (00:19:38) 10:09:01 INFO - javascript benchmark 10:09:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3306.6693330666935, 3318.8384065577047, 3328.0515053209833, 3350.430426827439, 3359.2945481448896, 5601.3558440779325, 5652.265205523287, 5685.9147112793235, 5692.459216374443, 5707.429257074301, 5590.329160500735, 5600.803971860975, 5676.3188417389865, 5684.2326285951385, 5696.11710184922, 5134.306868572751, 5145.74271286436, 5171.870703232412, 5189.792408303672, 5208.374995000605, 4338.17574660815, 5102.6428150029515, 5131.358580177478, 5140.563052140554, 5251.028559716446, 4480.775961201944, 4956.826511072113, 5100.132977393852, 5155.793768249266, 5178.7669554869935, 5163.173892177234, 5168.3539557555305, 5221.190715439113, 5226.947730522722, 5279.63042587019, 4079.2861249281555, 5138.820141295074, 5163.509466600667, 5180.1452760294505, 5189.987952349294, 5542.584306177013, 5568.52667523257, 5580.832575022756, 5590.748416321276, 5604.103343465067, 6088.1476593276975, 6111.388861113853, 6118.4799292060225, 6177.196964394645, 6182.0, 6271.247450305947, 6305.747770089191, 6310.053491976194, 6313.621182729005, 6314.116023756677, 5629.3526244482, 5671.234383358276, 5688.544916406678, 5731.914021289684, 5744.425557444222, 6288.465480434123, 6314.052892066226, 6324.272708638474, 6343.714532846033, 6354.523410744213, 6087.908681369783, 6096.756129754849, 6097.725602347905, 6105.206323177958, 6111.35830737775, 6073.6659483727935, 6115.240341084264, 6119.2350956130485, 6124.724387402578, 6158.907616385758, 6056.182415373956, 6106.786754545499, 6141.293751218667, 6158.168647232566, 6189.81430557084, 6071.241094863142, 6180.6600636965395, 6183.474404675563, 6202.41077097675, 6228.813135605939, 4841.709497430165, 4899.412070551557, 4901.9019619608125, 4907.533784290486, 4919.745464906426, 4337.978310108492, 4365.80353884076, 4379.890502737457, 4379.9781001095425, 4403.911921761547, 5720.42795720433, 5727.455891690366, 5744.856378590485, 5747.195392645033, 5762.423757624204, 6144.477719393813, 6262.655553944577, 6264.310925798159, 6266.216722909637, 6281.0, 5591.10542313228, 5592.496675299242, 5610.943890561042, 5738.799142030009, 5755.0, 5302.2046692996355, 5333.093374126408, 5356.303680521585, 5378.0, 5402.135658294654, 6263.655498947602, 6270.780522681593, 6283.403076707706, 6292.842678933063, 6301.873962520815, 4689.577937985598, 6272.0, 6279.403456671792, 6287.748490060347, 6289.811305660837, 6126.700892189639, 6182.7217775201125, 6202.100695399232, 6212.471939884979, 6264.59280146789, 6127.3566275540425, 6143.262808463013, 6150.415710507495, 6160.7535698571555, 6169.136320915165, 4638.814447422065, 6197.287311959182, 6208.037754148114, 6218.875622487434, 6237.844053898689, 4904.583110435653, 5820.592558520862, 5891.852703682443, 5946.881062378815, 5966.3138739043825, 5965.28416590012, 6020.337762845996, 6033.0, 6050.2437195350585, 6077.088436734349, 5871.6770577618645, 5881.794137205177, 5882.617629854046, 5902.2622172228475, 5907.940920590911, 5772.7690892363835, 5877.177195192591, 5929.52563794904, 6020.578559500793, 6043.425874541911, 5790.305163380422, 5885.323187833283, 5890.352061273171, 5893.086571581412, 5909.409059094056], "value": 5615.195145948225, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.7543053042949, 486.49404619196036, 509.43452767428147, 514.6731825290941, 527.599024741197, 29375.853120734395, 29917.850410747942, 30745.99999999997, 37840.62159378405, 39311.21377572447, 40718.18563628726, 41204.38193427101, 41307.0, 41341.7932910335, 41578.0, 9227.907720922789, 9371.812563748721, 9373.578188981512, 9376.203022743075, 9390.62437502499, 8317.500949942993, 8535.274501667365, 8539.786505337384, 9263.351565390425, 9350.906490935105, 8440.535770532619, 8518.87221691675, 10981.341119532854, 11241.325520468758, 11249.83125253122, 11120.499577519031, 11172.720681982933, 11184.32894026357, 11187.105031597534, 11187.552497900075, 11140.164487663376, 11175.385353805537, 11207.271527350696, 11221.943890280496, 11227.101831853513, 38709.419358709645, 39009.41485877714, 39066.0, 39150.41274380887, 39157.02107447308, 3160.5891234139644, 3249.8375081245845, 3295.555100061509, 3506.912327191815, 3800.5646269641525, 28442.004529841357, 28516.287092822637, 29841.403171936658, 30001.099967001024, 30569.847150764326, 3184.538241954927, 3216.710496055366, 3232.660570640096, 3235.1912021994503, 3310.6358300586917, 492678.0, 494920.5253973707, 495395.0, 495682.0, 495930.0, 32866.83566582152, 33672.83163584166, 58421.0, 58935.115973260436, 58984.70507647434, 34331.0, 34862.65137348594, 34902.82548587241, 34916.30167396638, 34917.476237856456, 14050.086744361584, 14191.0, 14215.36030878613, 14232.928835355964, 14301.141931484144, 12060.457279422271, 12120.878791211975, 12130.696732581757, 12153.453094610586, 12154.756904861853, 7152.678129484186, 7322.96338518304, 7649.38804895607, 7668.769936901902, 7703.653335599912, 3031.030070377502, 3131.248500359897, 3209.3420848725955, 3241.9837900810344, 3245.448273793461, 868.9391494327144, 877.5392918717724, 1062.9202809789297, 1088.3252383522267, 1103.4427614055012, 28832.279193020342, 29456.645785678113, 29880.850595747317, 29906.40187196244, 29971.0, 1015.6902144845745, 1036.1762398892897, 1087.445402844555, 1103.8068338040812, 1110.777844431117, 15225.390984360723, 15286.0, 15370.385184592491, 15515.767263491056, 15767.49873251629, 648823.0, 656031.0, 656492.0, 656509.0, 657513.0, 47876.28185577219, 55815.720921395136, 56264.71867640636, 56299.0, 56440.717796411576, 1198.2211562484456, 1212.4725744301081, 1256.5350820196586, 1330.215173047887, 1334.5395838435725, 27263.454730904876, 27429.217100888378, 30459.2385190372, 30720.539191912587, 30902.227444313172, 48943.26585101155, 49433.25850112323, 49462.258066129754, 49483.25775113448, 49514.50485495099, 534485.0, 538184.0, 538926.0, 539358.0, 659782.0, 39930.60069399269, 40272.597274026884, 40510.797446012584, 40511.59488405196, 40871.0, 44642.107157856015, 44742.0, 45025.0, 45041.774791125834, 45413.63759087233, 36948.26103477862, 37137.814310928276, 41110.18906407773, 41200.793996029824, 41499.60505134313, 37912.829588744666, 38690.80654596709, 38714.22571548497, 40955.0, 42258.7887060575, 37840.62159378371, 39625.603743963344, 39742.0, 39746.0, 40094.39858402068, 40037.599624003386, 40261.59738402579, 40364.3945340826, 40397.78806635806, 40436.191276173726, 31219.37561248808, 31248.843755781076, 31496.84251578819, 31515.84242078775, 32207.194820129684], "value": 18847.42245580966, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.23124242288185, 367.6801182970816, 371.8085186129142, 382.39008780994305, 400.105763618313, 14516.0, 14730.116193028412, 15296.31166597503, 16556.586085347873, 19521.902390488063, 18958.620827583476, 20019.299324523643, 20185.899070504664, 20287.0, 20439.795602043974, 5599.608027438071, 5913.763449462016, 5916.674582897939, 5920.792772252972, 5927.585069045169, 5540.030494663418, 5800.53595712342, 5841.853953651172, 5863.79476718315, 5872.882542349151, 5573.359063707665, 5817.796377126801, 5831.620944638585, 5891.440313170242, 5919.408059194089, 5933.525317974551, 5949.583529152961, 5972.641641501524, 5977.551683623702, 5980.970095149518, 5504.697241651707, 5652.491275785158, 5742.971285143548, 5743.597948143632, 5754.4533269339345, 13045.34773261333, 13083.803742943863, 13090.803637945439, 13283.0, 13284.40220190084, 2177.3359125466727, 2217.6673498975283, 2224.8887555622314, 2317.304808557426, 2464.445499762557, 10174.931632178588, 10177.287589868713, 10363.844542331872, 10473.528691208838, 13261.872740817042, 1729.8010728766228, 1759.6128851652616, 1783.3669047488215, 1790.9462716118537, 1840.3926704187586, 114568.0, 121538.0, 122036.0, 123449.38275308655, 124310.0, 11286.887131128748, 11468.025217856575, 11545.365004924643, 11558.30650160993, 11784.94107529474, 14576.635584110481, 14605.926970365294, 14636.341364638616, 14640.633984150269, 14644.707105857824, 8122.634481448349, 8397.202265784741, 8407.495550267004, 8594.0, 8597.355198360148, 6941.861162776817, 8253.79365515867, 8291.046529649167, 8292.17078292178, 8468.872966905501, 4346.717463364871, 4439.334099885043, 4469.910601787946, 4521.276595744665, 4528.954710452919, 1839.9880065963666, 1896.9620607587772, 1898.3260942365537, 1900.828925396721, 1901.4580844459263, 789.6801795272924, 854.8587635506668, 897.4660077254024, 933.9112784285481, 944.5135755086136, 20277.594448110955, 20912.47718807042, 20985.475363116046, 21315.0, 21473.785262147492, 928.3455164109287, 929.1683942871227, 949.4350861237552, 966.5022513405602, 969.3795970578835, 10745.355278683304, 11096.944515277533, 11135.387553684463, 11149.665510034712, 11189.384583847805, 589523.0, 594798.0, 600212.0, 614493.0, 624947.0, 89649.55175224213, 95232.0, 95721.52139239259, 128059.0, 141422.0, 880.6433394475248, 913.8263729891432, 992.3648864726581, 992.692265397729, 993.9602415903283, 17611.29554817844, 18065.2773889043, 18626.720599191292, 18804.835786014904, 18950.905245473685, 54706.45293547173, 55003.295583917745, 55143.72428137834, 56075.91976073557, 57907.71046144742, 79886.2011379902, 80794.0, 81525.59237203776, 82715.58642206991, 83951.16048839678, 6992.965035174791, 7647.235276472308, 7756.68973241064, 7887.526748395115, 8171.5505647189975, 8986.68546600866, 10517.15862730995, 11470.254433461798, 12264.570740024057, 13012.47950082024, 10879.347239165676, 10996.505157268102, 11072.501737421997, 11357.829632555684, 11429.828552571551, 10681.732956676145, 10814.243002989715, 10992.450377481255, 11136.0, 11266.436678166223, 11215.327080375204, 11223.88776112261, 11268.7746245073, 11502.0, 11560.47977841016, 7932.325752310888, 8033.076196237507, 8073.515589064675, 8461.153884611489, 8484.872726909225, 8291.502509849428, 10481.685549433529, 11023.779524409307, 11326.0, 11386.0], "value": 9243.867937266536, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1226.5277868020812, 1437.8346490153633, 1554.7590123530856, 1780.741792440097, 1814.5191524246077, 7947.483413578113, 8386.790330241733, 8397.832043359136, 8403.285720713746, 8403.873941890877, 7290.817729556764, 7581.2039735827775, 7586.544807311565, 7876.369890408768, 8094.838103237932, 2312.5721741477796, 2347.7182738071456, 2347.9647805282934, 2351.5179388225374, 2355.9293221203307, 2328.8835558222104, 2341.625339945609, 2373.3947843299934, 2373.667686523888, 2377.3699969508093, 2320.8607483550954, 2325.1745630301252, 2348.154664320843, 2350.5063936573342, 2351.5532048910736, 2313.6298192289323, 2320.2807129789735, 2342.7071616047992, 2343.824213184001, 2344.6600242964673, 2317.953640927189, 2340.4850932794757, 2346.378209774411, 2348.7533808950157, 2350.7766762157567, 7319.5242309249725, 8399.11809260031, 8410.537420441873, 8412.411131220817, 8437.071922088566, 5031.295618613397, 5056.6713163644245, 5220.4779522047875, 5223.242629818693, 5223.347081614798, 3144.9056528304186, 3348.8493017814258, 3392.6607339266125, 3407.522946787452, 3456.5333679953137, 1006.8791744990575, 1035.8705161854768, 1083.8482612434266, 1091.0725882999466, 1101.6529793115224, 10665.893341066645, 10764.569417223302, 10767.192460565411, 10768.192385571036, 10772.353658780497, 4474.440694913136, 4543.38664280318, 4625.121226966933, 4631.513691062457, 4736.313234580966, 9324.673636422693, 9333.0, 9333.30000249984, 9344.439333640003, 9355.391899526508, 5189.4032186299055, 5192.662476938987, 5194.740262986836, 5202.739863006835, 5202.843914682566, 4226.619604235633, 4357.67317451186, 4451.376807246988, 4732.858014259577, 4759.23852183649, 3757.9060523487133, 3795.7532760370486, 3852.8651497198025, 3874.341361968472, 3898.590647981978, 1233.9444724987397, 1265.1397050006085, 1266.0947422592706, 1267.8415198100238, 1277.9041571882146, 800.8518424091561, 875.7066382761719, 903.2864037410503, 921.8755468011733, 924.5654542365078, 5652.660840349592, 5795.681237531976, 5870.765169393262, 6023.728932198062, 6127.509799216052, 832.5421018439833, 897.708244820436, 911.5077857956762, 931.4411353188168, 975.658519518163, 3434.639362866913, 3840.30874442603, 3899.74651647642, 3904.7852368119457, 3953.920921581552, 8551.957240213758, 9646.710598682052, 10216.489175541343, 10229.693109206735, 10234.976502349706, 10159.644412445678, 10161.288709790244, 10173.0, 10187.694369168938, 10195.929427410163, 7345.449091318173, 7736.7678969631, 7772.494787838772, 7915.525068495908, 7918.802029949297, 4795.544423279783, 5731.598788084794, 5953.196318497121, 5968.552358573212, 6021.458068773832, 9013.414128081653, 9099.681511147079, 9182.311326650395, 9188.448693078437, 9286.72139835806, 7913.683452661829, 9239.72280831576, 9418.670346537838, 9527.285453591147, 9548.236141108833, 6508.08886755864, 6700.6984685688285, 6720.227173875067, 7034.261402552863, 7166.749163779243, 7495.475316727777, 7893.052833659768, 7899.881501777593, 7900.051993760785, 7995.400344974267, 7143.964280178566, 7208.387287080657, 7375.594342311224, 7377.77866664001, 7416.592087435027, 7099.858002840017, 7218.747343842941, 7287.234840341688, 7299.963500182465, 7309.0, 6425.58233714807, 6652.73389064432, 6654.101696270942, 6657.06801047844, 6670.366315200243, 5004.124278251219, 5011.09800235961, 5033.0688822567945, 5062.924056139087, 5081.974590127025, 4784.784684689128, 5022.518357084596, 5083.72039537829, 5177.145770947752, 5207.843764687065], "value": 4422.659515333747, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.58519243444044, 303.9073082709774, 310.18266866805976, 313.47962382445144, 317.43179708322106, 4261.446012018437, 5146.228065790134, 5188.0, 5209.426963034063, 5249.632525723201, 4392.538783427742, 4722.480527142012, 4876.853694389165, 5051.924221136685, 5179.8762507371985, 3952.6837852971835, 3991.501062367197, 4012.435150291374, 4014.658754005913, 4016.2689371227552, 3974.304496713078, 3983.382575700771, 4011.4154908811, 4012.6589239914642, 4028.7582745035247, 3849.961500384988, 3923.0, 3959.5644479107277, 3985.60143985602, 3994.980025099885, 3643.325984692827, 3959.8614048508166, 3968.4840970673627, 3988.8061566138654, 3993.940090898639, 3458.4639380896, 3487.843047062888, 3492.4761285807076, 3496.965030349689, 3511.20997775501, 4282.743035417854, 4957.0, 4959.727215003173, 4979.862686507513, 4991.251312303184, 3770.377887648562, 3984.561698213194, 4292.892677683051, 4446.199684056868, 4530.1392735380505, 4619.930701039487, 5273.839755253838, 5407.594430417735, 5433.483819037185, 5435.374931882845, 2883.135059482147, 3241.2545114623767, 3678.1356381250384, 3802.8098595070137, 3840.481534992768, 4759.381280433521, 5320.6275560710765, 5444.0, 5631.211630371751, 5643.520300774439, 3261.445554255782, 3472.6977383481194, 3629.8366573503727, 3891.1439483314193, 3896.883093507199, 3369.882054128094, 3452.896413107611, 3643.506162473373, 3901.804909754501, 3960.405939109099, 3523.0, 4506.662000349921, 4571.497135315112, 4574.81700731967, 4575.633949284049, 4666.803239780761, 5371.301730774974, 5426.9185962210595, 5471.644343117685, 5472.616916815864, 1104.7238190452388, 1278.3317770309834, 1279.8528169260653, 1290.7934730443085, 1292.5605294200018, 762.7940456076828, 794.9705131854147, 833.7993289662987, 854.0221446443787, 859.1709000814119, 4894.412670479565, 4895.485973972682, 5128.717920514333, 5146.919146979101, 5232.607554433433, 4514.133525211449, 5177.197534382177, 5552.305961754781, 5560.860978475571, 5564.916526252109, 3887.8639247626197, 4914.8034078637165, 5011.972545628138, 5103.540681338697, 5122.513361230677, 2141.785821417877, 2544.7328030556896, 2588.7799537039186, 2764.6129541864157, 2804.8036637435594, 4685.361492122774, 4731.0, 5509.3664228613425, 5510.779568817203, 5563.519932866124, 4558.632410276904, 4842.457709702435, 4897.877553061131, 5470.362209130963, 5477.931803298318, 4870.537298956593, 5476.91784623231, 5501.752421141058, 5503.669779813224, 5508.834734957958, 5267.920981185362, 5292.285541451854, 5295.920561191509, 5325.467453254643, 5327.547158491571, 4909.337239472626, 5162.948370516397, 5592.273004509388, 5625.718714064198, 5628.887422251614, 4823.614110871052, 5791.391903850035, 5846.56150788684, 5857.26784151981, 5869.148973398748, 3550.680438760524, 3776.66010059085, 3810.7713537187856, 3811.7903515305816, 3814.8092595369567, 4396.450443694538, 4604.5165257648805, 5518.7373176997235, 5628.887422251614, 5641.210230567806, 4515.706479078849, 5469.425710300361, 5494.928488944617, 5498.158117030839, 5508.752106155152, 5244.921326180034, 5430.479465749597, 5486.670799752028, 5512.6692398456225, 5515.531179849758, 4361.2367835629275, 4643.769401108641, 5381.650192737459, 5391.75737091824, 5394.379646340721, 4439.689221754446, 4934.013197360614, 5172.405173404956, 5290.9735451322495, 5317.335333083364, 4888.584470320063, 5145.562627176732, 5370.382406023201, 5383.7308134593895, 5418.241446197614], "value": 3917.9763594900505, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.8025512728873, 696.2132789947362, 766.995236240525, 778.2412148155546, 803.3533005930226, 14360.569182924502, 14753.557393278192, 14772.261386930664, 14782.113073215614, 14794.482193123244, 12857.357132143403, 12991.155574887625, 12991.545295914644, 12995.090343675924, 13009.219446833196, 636.350922059499, 652.0935899100255, 653.5947712418307, 778.5640041576721, 780.4068907630185, 803.2248879830962, 804.7424824056304, 816.0656048824093, 816.2001238785964, 817.8037271054964, 849.5242664108089, 850.1371108325055, 850.2517784349764, 850.3537311643134, 852.2755657690944, 657.1424291299852, 672.3008071605525, 846.2172490446312, 847.7838151271447, 848.2281124177, 610.9755609775603, 627.4447114303874, 628.0140179917531, 628.5882746800853, 629.2511910826103, 13449.66375840602, 13467.259300738455, 13478.39347229377, 13500.257485838274, 13554.0, 12455.688607784787, 12456.75086498265, 12592.0, 12656.0, 12734.61796146117, 11896.940515297454, 11918.940405298004, 11925.88074119265, 12011.339376334297, 12062.577809776616, 4107.0, 4109.219248342805, 4183.91632167355, 4327.415798867176, 4353.368761529592, 83396.0, 87118.56440717819, 87823.0, 89856.10143898608, 90521.0, 9991.650292239881, 10158.339707918962, 10233.897661023295, 10415.010573995458, 10511.737206569744, 18591.721124183146, 19120.948347840724, 19235.4229373119, 19268.614627707368, 19353.612927741367, 7759.495632783851, 7819.452638315377, 7844.176361482077, 7941.36469082472, 7946.801329966682, 4180.080382315886, 4232.830686772495, 4370.0, 4430.911381772346, 4454.28731402974, 3381.205416727067, 3498.2653642734804, 3505.859765609398, 3506.01831487184, 3513.0690367052753, 655.3119224813927, 655.6295692933477, 660.8513084555937, 666.1140682891743, 675.8682056998838, 524.3917056214773, 572.2532095615262, 629.8929182039064, 660.2638058564665, 667.1393901866538, 7798.0, 8986.775330616787, 9084.083710800998, 9212.815743685089, 9297.209737172416, 561.9185218143347, 605.198112500938, 648.8604949935786, 655.2039272284197, 682.3858527325447, 4055.6958228133008, 4240.618344349024, 4321.75013021801, 4515.128314756464, 4591.79336929839, 68860.31139688539, 71078.0, 73766.8934965976, 110479.0, 111281.0, 95379.0, 95382.52308738412, 99708.0, 105395.0, 107584.0, 86832.0, 89128.0, 90047.0, 90451.54774226087, 90683.54658226931, 7787.844243115219, 8512.957435213031, 8781.990071141901, 8823.911760882565, 9069.546522673973, 57014.714926425106, 57066.7146664264, 57213.42786572248, 57699.0, 58372.70813645905, 69949.0, 79418.0, 81087.59456202682, 83981.0, 84808.57595711981, 12001.579944702244, 12003.339816310187, 12380.4428800706, 12519.123661343983, 12584.119111662096, 13330.26683532415, 14337.713245734818, 14443.78334324965, 15035.0, 15069.09585424878, 11213.383263920236, 12893.097483176087, 12939.482420703067, 13402.93298533501, 13784.586462406143, 11964.0, 13110.672233194246, 13240.53658121961, 13266.137701049402, 13628.0, 12889.355532223539, 12965.15726477776, 12966.0, 13171.670708231988, 13248.735025299633, 10876.673699789018, 11263.718407039889, 11421.657350279505, 11533.192676512563, 11727.824082638937, 9919.801603968024, 11349.262297950607, 11407.25852819564, 11495.654520647939, 11504.942475287904], "value": 7237.185997203497, "unit": "score"}], "name": "dromaeo_css", "value": 7049.182035254685, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 115.0, 115.0, 114.0, 115.0, 114.0, 113.0, 115.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 112.0, 111.0, 146.0, 112.0, 110.0, 111.0, 108.0, 139.0, 110.0], "value": 120.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 182.0, 184.0, 184.0, 181.0, 185.0, 187.0, 185.0, 186.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 59.0, 88.0, 62.0, 60.0, 63.0, 58.0, 82.0, 58.0], "value": 67.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 95.0, 70.0, 69.0, 71.0, 89.0, 68.0, 69.0, 92.0], "value": 78.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 87.0, 90.0, 86.0, 89.0, 87.0, 89.0, 85.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 100.0, 97.0, 96.0, 97.0, 111.0, 133.0, 97.0, 96.0, 97.0], "value": 103.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 79.0, 79.0, 83.0, 86.0, 86.0, 84.0, 82.0, 81.0], "value": 82.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 53.0, 53.0, 57.0, 59.0, 59.0, 57.0, 57.0, 57.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 78.0, 77.0, 76.0, 72.0, 70.0, 71.0, 70.0, 71.0, 72.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 82.0, 85.0, 83.0, 86.0, 83.0, 85.0, 87.0, 89.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 148.0, 145.0, 143.0, 139.0, 141.0, 143.0, 143.0, 181.0, 155.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 169.0, 165.0, 165.0, 167.0, 166.0, 166.0, 165.0, 169.0], "value": 166.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 68.0, 67.0, 66.0, 67.0, 68.0, 67.0, 68.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1433.8999999999999, "alertThreshold": 2.0}]} 10:09:01 INFO - Return code: 0 10:09:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:09:01 INFO - # TBPL SUCCESS # 10:09:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:09:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:09:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:09:01 INFO - 2017-01-13 10:09:01.660 screenresolution[1649:9721] starting screenresolution argv=screenresolution get 10:09:01 INFO - 2017-01-13 10:09:01.663 screenresolution[1649:9721] Display 0: 1600x1200x32@60 10:09:01 INFO - 2017-01-13 10:09:01.670 screenresolution[1650:9724] starting screenresolution argv=screenresolution list 10:09:01 INFO - Available Modes on Display 0 10:09:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:09:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:09:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:09:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:09:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:09:01 INFO - Intel Iris: 10:09:01 INFO - Chipset Model: Intel Iris 10:09:01 INFO - Type: GPU 10:09:01 INFO - Bus: Built-In 10:09:01 INFO - VRAM (Dynamic, Max): 1536 MB 10:09:01 INFO - Vendor: Intel (0x8086) 10:09:01 INFO - Device ID: 0x0a2e 10:09:01 INFO - Revision ID: 0x0009 10:09:01 INFO - Displays: 10:09:01 INFO - Display: 10:09:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:09:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:09:01 INFO - Display Serial Number: 1600x1200 60 10:09:01 INFO - Main Display: Yes 10:09:01 INFO - Mirror: Off 10:09:01 INFO - Online: Yes 10:09:01 INFO - Rotation: Supported 10:09:01 INFO - Return code: 0 10:09:01 INFO - Running post-action listener: _resource_record_post_action 10:09:01 INFO - [mozharness: 2017-01-13 18:09:01.914258Z] Finished run-tests step (success) 10:09:01 INFO - Running post-run listener: _resource_record_post_run 10:09:01 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 37741568; Write bytes: 653248000; Read time: 237; Write time: 1848 10:09:01 INFO - TinderboxPrint: CPU usage
53.5% 10:09:01 INFO - TinderboxPrint: I/O read bytes / time
37,741,568 / 237 10:09:01 INFO - TinderboxPrint: I/O write bytes / time
653,248,000 / 1,848 10:09:01 INFO - TinderboxPrint: CPU idle
2,252.2 (46.5%) 10:09:01 INFO - TinderboxPrint: CPU system
970.8 (20.1%) 10:09:01 INFO - TinderboxPrint: CPU user
1,618.7 (33.4%) 10:09:01 INFO - TinderboxPrint: Swap in / out
386,723,840 / 0 10:09:01 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 343155200; Write bytes: 337487872; Read time: 26710; Write time: 678 10:09:01 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 35914752; Write bytes: 300318208; Read time: 222; Write time: 1123 10:09:02 INFO - Running post-run listener: _upload_blobber_files 10:09:02 INFO - Blob upload gear active. 10:09:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:09:02 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 10:09:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:09:02 INFO - (blobuploader) - INFO - Open directory for files ... 10:09:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:09:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:09:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:04 INFO - (blobuploader) - INFO - Done attempting. 10:09:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:09:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:09:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:05 INFO - (blobuploader) - INFO - Done attempting. 10:09:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:09:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:09:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:07 INFO - (blobuploader) - INFO - Done attempting. 10:09:07 INFO - (blobuploader) - INFO - Iteration through files over. 10:09:07 INFO - Return code: 0 10:09:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:09:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:09:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0be2d10fd41569807947b52d867a44a42a0be3b4d9104014dd2a15c08fa5a18c6276524b8640c4291f0d011beb1ae0e986bc12dbad35b971f8884bfc840b6417", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/945011040240df3c32d1e32f97ae65f6ac6de145d8bf16a65a39b94f33f122910ddb522c2aa2b1e60ee7b13c6e3f2561898eec589a4d5fc8f782cb22d919510c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/26716f7cb3ec38d89145927fb9254aa5bd98ac23f6dfcdc462ea56fb4cd70e4a72c387322a6d9b413c7f494c58d4d0a8254707e39174bd783d9cfac636819caf"} 10:09:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:09:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:09:07 INFO - Contents: 10:09:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0be2d10fd41569807947b52d867a44a42a0be3b4d9104014dd2a15c08fa5a18c6276524b8640c4291f0d011beb1ae0e986bc12dbad35b971f8884bfc840b6417", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/945011040240df3c32d1e32f97ae65f6ac6de145d8bf16a65a39b94f33f122910ddb522c2aa2b1e60ee7b13c6e3f2561898eec589a4d5fc8f782cb22d919510c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/26716f7cb3ec38d89145927fb9254aa5bd98ac23f6dfcdc462ea56fb4cd70e4a72c387322a6d9b413c7f494c58d4d0a8254707e39174bd783d9cfac636819caf"} 10:09:07 INFO - Running post-run listener: copy_logs_to_upload_dir 10:09:07 INFO - Copying logs to upload dir... 10:09:07 INFO - mkdir: /builds/slave/test/build/upload/logs 10:09:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.626521 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-13 10:09:07.860719) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 10:09:07.863894) ========= 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.FycmsRb41Z/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.5FwuIsPspK/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0be2d10fd41569807947b52d867a44a42a0be3b4d9104014dd2a15c08fa5a18c6276524b8640c4291f0d011beb1ae0e986bc12dbad35b971f8884bfc840b6417", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/945011040240df3c32d1e32f97ae65f6ac6de145d8bf16a65a39b94f33f122910ddb522c2aa2b1e60ee7b13c6e3f2561898eec589a4d5fc8f782cb22d919510c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/26716f7cb3ec38d89145927fb9254aa5bd98ac23f6dfcdc462ea56fb4cd70e4a72c387322a6d9b413c7f494c58d4d0a8254707e39174bd783d9cfac636819caf"} build_url:https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011180 build_url: 'https://queue.taskcluster.net/v1/task/bCfFMAXYSSmdCgtVNxFd5w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0be2d10fd41569807947b52d867a44a42a0be3b4d9104014dd2a15c08fa5a18c6276524b8640c4291f0d011beb1ae0e986bc12dbad35b971f8884bfc840b6417", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/945011040240df3c32d1e32f97ae65f6ac6de145d8bf16a65a39b94f33f122910ddb522c2aa2b1e60ee7b13c6e3f2561898eec589a4d5fc8f782cb22d919510c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/26716f7cb3ec38d89145927fb9254aa5bd98ac23f6dfcdc462ea56fb4cd70e4a72c387322a6d9b413c7f494c58d4d0a8254707e39174bd783d9cfac636819caf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 10:09:07.897453) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 10:09:07.897788) ========= 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.FycmsRb41Z/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.5FwuIsPspK/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.005067 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 10:09:07.946258) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 10:09:07.946578) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 10:09:07.946890) ========= ========= Total master_lag: 0.17 =========