builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0135 starttime: 1486065853.24 results: success (0) buildid: 20170202101509 builduid: 9bec15c897d74b64af1f2f9d2986fa76 revision: d171c36d484800b1bb00db1612460a7120dd2fdf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 12:04:13.235630) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 12:04:13.236063) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 12:04:13.236352) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 12:04:13.261598) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 12:04:13.261878) ========= 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.0PfSq3bjQz/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.BP0r6IFkAX/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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-02-02 12:04:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.6M=0.001s 2017-02-02 12:04:13 (14.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.162176 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 12:04:13.447982) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 12:04:13.451321) ========= 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.0PfSq3bjQz/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.BP0r6IFkAX/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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.034076 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 12:04:13.506826) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 12:04:13.507161) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d171c36d484800b1bb00db1612460a7120dd2fdf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d171c36d484800b1bb00db1612460a7120dd2fdf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0PfSq3bjQz/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.BP0r6IFkAX/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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-02-02 12:04:13,612 truncating revision to first 12 chars 2017-02-02 12:04:13,612 Setting DEBUG logging. 2017-02-02 12:04:13,613 attempt 1/10 2017-02-02 12:04:13,613 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d171c36d4848?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-02 12:04:14,977 unpacking tar archive at: mozilla-beta-d171c36d4848/testing/mozharness/ program finished with exit code 0 elapsedTime=1.634549 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 12:04:15.167698) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 12:04:15.168081) ========= script_repo_revision: d171c36d484800b1bb00db1612460a7120dd2fdf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 12:04:15.171579) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 12:04:15.171847) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 12:04:15.191806) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-02 12:04:15.192168) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0PfSq3bjQz/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.BP0r6IFkAX/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 12:04:15 INFO - MultiFileLogger online at 20170202 12:04:15 in /builds/slave/test 12:04:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 12:04:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:04:15 INFO - {'append_to_log': False, 12:04:15 INFO - 'base_work_dir': '/builds/slave/test', 12:04:15 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:04:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:04:15 INFO - 'branch': 'Mozilla-Beta', 12:04:15 INFO - 'buildbot_json_path': 'buildprops.json', 12:04:15 INFO - 'config_files': ('talos/mac_config.py',), 12:04:15 INFO - 'default_actions': ('clobber', 12:04:15 INFO - 'read-buildbot-config', 12:04:15 INFO - 'download-and-extract', 12:04:15 INFO - 'populate-webroot', 12:04:15 INFO - 'create-virtualenv', 12:04:15 INFO - 'install', 12:04:15 INFO - 'run-tests'), 12:04:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:04:15 INFO - 'download_minidump_stackwalk': True, 12:04:15 INFO - 'download_symbols': 'ondemand', 12:04:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:04:15 INFO - 'tooltool.py': '/tools/tooltool.py', 12:04:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:04:15 INFO - '/tools/misc-python/virtualenv.py')}, 12:04:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:04:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:04:15 INFO - 'installer_path': 'installer.exe', 12:04:15 INFO - 'log_level': 'info', 12:04:15 INFO - 'log_name': 'talos', 12:04:15 INFO - 'log_to_console': True, 12:04:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:04:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:04:15 INFO - 'opt_config_files': (), 12:04:15 INFO - 'pip_index': False, 12:04:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:04:15 INFO - 'cmd': ('bash', 12:04:15 INFO - '-c', 12:04:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:04:15 INFO - 'enabled': True, 12:04:15 INFO - 'halt_on_failure': False, 12:04:15 INFO - 'name': 'check_screen_resolution'},), 12:04:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:04:15 INFO - 'cmd': ('bash', 12:04:15 INFO - '-c', 12:04:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:04:15 INFO - 'enabled': True, 12:04:15 INFO - 'halt_on_failure': False, 12:04:15 INFO - 'name': 'check_screen_resolution'},), 12:04:15 INFO - 'run_cmd_checks_enabled': True, 12:04:15 INFO - 'sps_profile': False, 12:04:15 INFO - 'sps_profile_interval': 0, 12:04:15 INFO - 'suite': 'dromaeojs-e10s', 12:04:15 INFO - 'system_bits': '32', 12:04:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:04:15 INFO - 'title': 't-yosemite-r7-0135', 12:04:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:04:15 INFO - 'use_talos_json': True, 12:04:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:04:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:04:15 INFO - 'work_dir': 'build'} 12:04:15 INFO - [mozharness: 2017-02-02 20:04:15.405226Z] Running clobber step. 12:04:15 INFO - Running pre-action listener: _resource_record_pre_action 12:04:15 INFO - Running main action method: clobber 12:04:15 INFO - rmtree: /builds/slave/test/build 12:04:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:04:17 INFO - Running post-action listener: _resource_record_post_action 12:04:17 INFO - [mozharness: 2017-02-02 20:04:17.863783Z] Finished clobber step (success) 12:04:17 INFO - [mozharness: 2017-02-02 20:04:17.863937Z] Running read-buildbot-config step. 12:04:17 INFO - Running pre-action listener: _resource_record_pre_action 12:04:17 INFO - Running main action method: read_buildbot_config 12:04:17 INFO - Using buildbot properties: 12:04:17 INFO - { 12:04:17 INFO - "project": "", 12:04:17 INFO - "product": "firefox", 12:04:17 INFO - "script_repo_revision": "production", 12:04:17 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 12:04:17 INFO - "repository": "", 12:04:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 12:04:17 INFO - "buildid": "20170202101509", 12:04:17 INFO - "pgo_build": "False", 12:04:17 INFO - "basedir": "/builds/slave/test", 12:04:17 INFO - "buildnumber": 4, 12:04:17 INFO - "slavename": "t-yosemite-r7-0135", 12:04:17 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:04:17 INFO - "platform": "yosemite_r7", 12:04:17 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 12:04:17 INFO - "branch": "mozilla-beta", 12:04:17 INFO - "repo_path": "releases/mozilla-beta", 12:04:17 INFO - "revision": "d171c36d484800b1bb00db1612460a7120dd2fdf", 12:04:17 INFO - "stage_platform": "macosx64", 12:04:17 INFO - "builduid": "9bec15c897d74b64af1f2f9d2986fa76", 12:04:17 INFO - "slavebuilddir": "test" 12:04:17 INFO - } 12:04:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 12:04:17 INFO - Running post-action listener: _resource_record_post_action 12:04:17 INFO - [mozharness: 2017-02-02 20:04:17.867339Z] Finished read-buildbot-config step (success) 12:04:17 INFO - [mozharness: 2017-02-02 20:04:17.867473Z] Running download-and-extract step. 12:04:17 INFO - Running pre-action listener: _resource_record_pre_action 12:04:17 INFO - Running main action method: download_and_extract 12:04:17 INFO - mkdir: /builds/slave/test/build/tests 12:04:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:17 INFO - trying https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 12:04:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 12:04:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 12:04:18 INFO - Downloaded 1165 bytes. 12:04:18 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 12:04:18 INFO - Using the following test package requirements: 12:04:18 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 12:04:18 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 12:04:18 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 12:04:18 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 12:04:18 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 12:04:18 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:04:18 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 12:04:18 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 12:04:18 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 12:04:18 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 12:04:18 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 12:04:18 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 12:04:18 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 12:04:18 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 12:04:18 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 12:04:18 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 12:04:18 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 12:04:18 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 12:04:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 12:04:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 12:04:18 INFO - Fetch https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 12:04:19 INFO - Http code: 200 12:04:19 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 12:04:19 INFO - x-amz-cf-id: 3TWV_pDSOFsfRxHKTrsObVJ3tG-dknx0fvHVXYplFx-BV5xdKNAcHA== 12:04:19 INFO - x-amz-version-id: YdF2dyn0buDcH7Z6wQRrsWx8dsfQ0Lsi 12:04:19 INFO - Expected file size: 16091995 12:04:19 INFO - Obtained file size: 16091995 12:04:26 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 12:04:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 12:04:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 12:04:26 INFO - Fetch https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 12:04:27 INFO - Http code: 200 12:04:27 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 12:04:27 INFO - x-amz-cf-id: EDmqLj0P35pLT4zQQmcKcPu-wJYfKypPfwUHqeZkR3IHUrcjZ8lgbw== 12:04:27 INFO - x-amz-version-id: YdF2dyn0buDcH7Z6wQRrsWx8dsfQ0Lsi 12:04:27 INFO - Expected file size: 16091995 12:04:27 INFO - Obtained file size: 16091995 12:04:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 12:04:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 12:04:34 INFO - Fetch https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 12:04:34 INFO - Http code: 200 12:04:34 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 12:04:34 INFO - x-amz-cf-id: xy9APHD4jeQ0vGIOy1x7-vDabfkNsVxzbKy_CoUIsL-g8LNcg5M1WA== 12:04:34 INFO - x-amz-version-id: BPuoM8ca43Begd6kRW1RQiQN9dboo4tR 12:04:35 INFO - Expected file size: 14403005 12:04:35 INFO - Obtained file size: 14403005 12:04:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:35 INFO - trying https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.dmg 12:04:35 INFO - Downloading https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 12:04:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 12:04:37 INFO - Downloaded 82131097 bytes. 12:04:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.dmg 12:04:37 INFO - mkdir: /builds/slave/test/properties 12:04:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:04:37 INFO - Writing to file /builds/slave/test/properties/build_url 12:04:37 INFO - Contents: 12:04:37 INFO - build_url:https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.dmg 12:04:38 INFO - Running post-action listener: _resource_record_post_action 12:04:38 INFO - Running post-action listener: set_extra_try_arguments 12:04:38 INFO - [mozharness: 2017-02-02 20:04:38.510329Z] Finished download-and-extract step (success) 12:04:38 INFO - [mozharness: 2017-02-02 20:04:38.510442Z] Running populate-webroot step. 12:04:38 INFO - Running pre-action listener: _resource_record_pre_action 12:04:38 INFO - Running main action method: populate_webroot 12:04:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:04:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:04:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:04:38 INFO - '--tppagecycles', 12:04:38 INFO - '7'], 12:04:38 INFO - 'tests': ['tsvgm']}}, 12:04:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:04:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:04:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:04:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:04:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:04:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:04:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:04:38 INFO - 'talos_options': ['--disable-e10s'], 12:04:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:04:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:04:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:04:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:04:38 INFO - 'talos_options': ['--disable-e10s'], 12:04:38 INFO - 'tests': ['damp', 'tps']}, 12:04:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:04:38 INFO - 'tests': ['damp', 'tps']}, 12:04:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:04:38 INFO - 'tests': ['dromaeo_dom']}, 12:04:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:04:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:04:38 INFO - 'tests': ['basic_compositor_video']}, 12:04:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:04:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:04:38 INFO - 'tests': ['a11yr', 12:04:38 INFO - 'ts_paint', 12:04:38 INFO - 'tpaint', 12:04:38 INFO - 'sessionrestore', 12:04:38 INFO - 'sessionrestore_no_auto_restore', 12:04:38 INFO - 'tabpaint']}, 12:04:38 INFO - 'other-e10s': {'tests': ['a11yr', 12:04:38 INFO - 'ts_paint', 12:04:38 INFO - 'tpaint', 12:04:38 INFO - 'sessionrestore', 12:04:38 INFO - 'sessionrestore_no_auto_restore', 12:04:38 INFO - 'tabpaint']}, 12:04:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:04:38 INFO - 'ts_paint', 12:04:38 INFO - 'tpaint', 12:04:38 INFO - 'sessionrestore', 12:04:38 INFO - 'sessionrestore_no_auto_restore', 12:04:38 INFO - 'tabpaint']}, 12:04:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:04:38 INFO - 'ts_paint', 12:04:38 INFO - 'tpaint', 12:04:38 INFO - 'sessionrestore', 12:04:38 INFO - 'sessionrestore_no_auto_restore', 12:04:38 INFO - 'tabpaint']}, 12:04:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:04:38 INFO - 'tests': ['a11yr', 12:04:38 INFO - 'ts_paint', 12:04:38 INFO - 'tpaint', 12:04:38 INFO - 'sessionrestore', 12:04:38 INFO - 'sessionrestore_no_auto_restore', 12:04:38 INFO - 'tabpaint']}, 12:04:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:04:38 INFO - 'tests': ['a11yr', 12:04:38 INFO - 'ts_paint', 12:04:38 INFO - 'tpaint', 12:04:38 INFO - 'sessionrestore', 12:04:38 INFO - 'sessionrestore_no_auto_restore', 12:04:38 INFO - 'tabpaint']}, 12:04:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:04:38 INFO - 'tests': ['tsvgx', 12:04:38 INFO - 'tsvgr_opacity', 12:04:38 INFO - 'tart', 12:04:38 INFO - 'tscrollx', 12:04:38 INFO - 'cart']}, 12:04:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:04:38 INFO - 'tsvgr_opacity', 12:04:38 INFO - 'tart', 12:04:38 INFO - 'tscrollx', 12:04:38 INFO - 'cart']}, 12:04:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:04:38 INFO - 'talos_options': ['--disable-e10s'], 12:04:38 INFO - 'tests': ['tp5o']}, 12:04:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:04:38 INFO - 'tests': ['tp5o']}, 12:04:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:04:38 INFO - 'talos_options': ['--disable-e10s', 12:04:38 INFO - '--xperf_path', 12:04:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:04:38 INFO - 'tests': ['tp5n']}, 12:04:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:04:38 INFO - 'talos_options': ['--xperf_path', 12:04:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:04:38 INFO - 'tests': ['tp5n']}}, 12:04:38 INFO - 'talos.zip': {'path': '', 12:04:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:04:38 INFO - Running post-action listener: _resource_record_post_action 12:04:38 INFO - [mozharness: 2017-02-02 20:04:38.529646Z] Finished populate-webroot step (success) 12:04:38 INFO - [mozharness: 2017-02-02 20:04:38.529756Z] Running create-virtualenv step. 12:04:38 INFO - Running pre-action listener: _resource_record_pre_action 12:04:38 INFO - Running main action method: create_virtualenv 12:04:38 INFO - Creating virtualenv /builds/slave/test/build/venv 12:04:38 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:04:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:04:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:04:38 INFO - Using real prefix '/tools/python27' 12:04:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:04:39 INFO - Installing distribute.............................................................................................................................................................................................done. 12:04:42 INFO - Installing pip.................done. 12:04:42 INFO - Return code: 0 12:04:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:04:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:04:42 INFO - Reading from file tmpfile_stdout 12:04:42 INFO - Output received: 12:04:42 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) 12:04:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:04:42 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')]} 12:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:42 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')]} 12:04:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:42 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 0x10a895490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a80b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc888d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a9d30>, '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 0x10a50fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a1b20>, '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.0PfSq3bjQz/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.BP0r6IFkAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 12:04:42 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 12:04:42 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 12:04:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PfSq3bjQz/Render', 12:04:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:04:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:04:42 INFO - 'HOME': '/Users/cltbld', 12:04:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:04:42 INFO - 'LOGNAME': 'cltbld', 12:04:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:42 INFO - 'MOZ_NO_REMOTE': '1', 12:04:42 INFO - 'NO_EM_RESTART': '1', 12:04:42 INFO - 'PAGER': '/bin/cat', 12:04:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:04:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:42 INFO - 'PWD': '/builds/slave/test', 12:04:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:04:42 INFO - 'SHELL': '/bin/bash', 12:04:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BP0r6IFkAX/Listeners', 12:04:42 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 12:04:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:04:42 INFO - 'USER': 'cltbld', 12:04:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:04:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:04:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:42 INFO - 'XPC_FLAGS': '0x0', 12:04:42 INFO - 'XPC_SERVICE_NAME': '0', 12:04:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:04:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:42 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 12:04:42 INFO - Cleaning up... 12:04:42 INFO - Return code: 0 12:04:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:04:42 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')]} 12:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:42 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')]} 12:04:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:42 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 0x10a895490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a80b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc888d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a9d30>, '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 0x10a50fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a1b20>, '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.0PfSq3bjQz/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.BP0r6IFkAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 12:04:42 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 12:04:42 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 12:04:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PfSq3bjQz/Render', 12:04:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:04:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:04:42 INFO - 'HOME': '/Users/cltbld', 12:04:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:04:42 INFO - 'LOGNAME': 'cltbld', 12:04:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:42 INFO - 'MOZ_NO_REMOTE': '1', 12:04:42 INFO - 'NO_EM_RESTART': '1', 12:04:42 INFO - 'PAGER': '/bin/cat', 12:04:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:04:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:42 INFO - 'PWD': '/builds/slave/test', 12:04:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:04:42 INFO - 'SHELL': '/bin/bash', 12:04:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BP0r6IFkAX/Listeners', 12:04:42 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 12:04:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:04:42 INFO - 'USER': 'cltbld', 12:04:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:04:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:04:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:42 INFO - 'XPC_FLAGS': '0x0', 12:04:42 INFO - 'XPC_SERVICE_NAME': '0', 12:04:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:04:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:43 INFO - Downloading/unpacking psutil>=3.1.1 12:04:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:04:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:04:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:04:46 INFO - Installing collected packages: psutil 12:04:46 INFO - Running setup.py install for psutil 12:04:46 INFO - building 'psutil._psutil_osx' extension 12:04:46 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 12:04:47 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 12:04:47 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 12:04:47 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 12:04:47 INFO - building 'psutil._psutil_posix' extension 12:04:47 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 12:04:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:04:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:04:47 INFO - ^ 12:04:47 INFO - 1 warning generated. 12:04:47 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 12:04:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:04:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:04:47 INFO - Successfully installed psutil 12:04:47 INFO - Cleaning up... 12:04:47 INFO - Return code: 0 12:04:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:04:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a895490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a80b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc888d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a9d30>, '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 0x10a50fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a1b20>, '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.0PfSq3bjQz/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.BP0r6IFkAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 12:04:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:04:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:04:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PfSq3bjQz/Render', 12:04:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:04:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:04:47 INFO - 'HOME': '/Users/cltbld', 12:04:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:04:47 INFO - 'LOGNAME': 'cltbld', 12:04:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:47 INFO - 'MOZ_NO_REMOTE': '1', 12:04:47 INFO - 'NO_EM_RESTART': '1', 12:04:47 INFO - 'PAGER': '/bin/cat', 12:04:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:04:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:47 INFO - 'PWD': '/builds/slave/test', 12:04:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:04:47 INFO - 'SHELL': '/bin/bash', 12:04:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BP0r6IFkAX/Listeners', 12:04:47 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 12:04:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:04:47 INFO - 'USER': 'cltbld', 12:04:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:04:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:04:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:47 INFO - 'XPC_FLAGS': '0x0', 12:04:47 INFO - 'XPC_SERVICE_NAME': '0', 12:04:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:04:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:04:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:04:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:04:50 INFO - Installing collected packages: mozsystemmonitor 12:04:50 INFO - Running setup.py install for mozsystemmonitor 12:04:50 INFO - Successfully installed mozsystemmonitor 12:04:50 INFO - Cleaning up... 12:04:50 INFO - Return code: 0 12:04:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a895490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a80b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc888d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a9d30>, '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 0x10a50fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a1b20>, '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.0PfSq3bjQz/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.BP0r6IFkAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 12:04: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==2.5.1'] in /builds/slave/test/build 12:04: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==2.5.1 12:04:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PfSq3bjQz/Render', 12:04:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:04:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:04:50 INFO - 'HOME': '/Users/cltbld', 12:04:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:04:50 INFO - 'LOGNAME': 'cltbld', 12:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:50 INFO - 'MOZ_NO_REMOTE': '1', 12:04:50 INFO - 'NO_EM_RESTART': '1', 12:04:50 INFO - 'PAGER': '/bin/cat', 12:04:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:04:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:50 INFO - 'PWD': '/builds/slave/test', 12:04:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:04:50 INFO - 'SHELL': '/bin/bash', 12:04:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BP0r6IFkAX/Listeners', 12:04:50 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 12:04:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:04:50 INFO - 'USER': 'cltbld', 12:04:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:04:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:50 INFO - 'XPC_FLAGS': '0x0', 12:04:50 INFO - 'XPC_SERVICE_NAME': '0', 12:04:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:04:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:51 INFO - Downloading/unpacking jsonschema==2.5.1 12:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:04:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:04:53 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 12:04:53 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 12:04:53 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 12:04:53 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 12:04:53 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 12:04:53 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 12:04:54 INFO - Downloading functools32-3.2.3-2.tar.gz 12:04:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:04:54 INFO - warning: no files found matching '*.txt' 12:04:54 INFO - no previously-included directories found matching 'build' 12:04:54 INFO - no previously-included directories found matching 'dist' 12:04:54 INFO - no previously-included directories found matching '.git*' 12:04:54 INFO - Installing collected packages: jsonschema, functools32 12:04:54 INFO - Running setup.py install for functools32 12:04:54 INFO - warning: no files found matching '*.txt' 12:04:54 INFO - no previously-included directories found matching 'build' 12:04:54 INFO - no previously-included directories found matching 'dist' 12:04:54 INFO - no previously-included directories found matching '.git*' 12:04:54 INFO - Successfully installed jsonschema functools32 12:04:54 INFO - Cleaning up... 12:04:54 INFO - Return code: 0 12:04:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a895490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a80b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc888d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a9d30>, '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 0x10a50fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a1b20>, '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.0PfSq3bjQz/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.BP0r6IFkAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 12:04:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:04:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PfSq3bjQz/Render', 12:04:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:04:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:04:54 INFO - 'HOME': '/Users/cltbld', 12:04:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:04:54 INFO - 'LOGNAME': 'cltbld', 12:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:54 INFO - 'MOZ_NO_REMOTE': '1', 12:04:54 INFO - 'NO_EM_RESTART': '1', 12:04:54 INFO - 'PAGER': '/bin/cat', 12:04:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:04:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:54 INFO - 'PWD': '/builds/slave/test', 12:04:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:04:54 INFO - 'SHELL': '/bin/bash', 12:04:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BP0r6IFkAX/Listeners', 12:04:54 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 12:04:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:04:54 INFO - 'USER': 'cltbld', 12:04:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:04:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:54 INFO - 'XPC_FLAGS': '0x0', 12:04:54 INFO - 'XPC_SERVICE_NAME': '0', 12:04:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:04:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:04:54 INFO - Cleaning up... 12:04:54 INFO - Return code: 0 12:04:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a895490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a80b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc888d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a9d30>, '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 0x10a50fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a1b20>, '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.0PfSq3bjQz/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.BP0r6IFkAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 12:04:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:04:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PfSq3bjQz/Render', 12:04:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:04:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:04:54 INFO - 'HOME': '/Users/cltbld', 12:04:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:04:54 INFO - 'LOGNAME': 'cltbld', 12:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:54 INFO - 'MOZ_NO_REMOTE': '1', 12:04:54 INFO - 'NO_EM_RESTART': '1', 12:04:54 INFO - 'PAGER': '/bin/cat', 12:04:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:04:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:54 INFO - 'PWD': '/builds/slave/test', 12:04:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:04:54 INFO - 'SHELL': '/bin/bash', 12:04:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BP0r6IFkAX/Listeners', 12:04:54 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 12:04:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:04:54 INFO - 'USER': 'cltbld', 12:04:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:04:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:54 INFO - 'XPC_FLAGS': '0x0', 12:04:54 INFO - 'XPC_SERVICE_NAME': '0', 12:04:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:04:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:55 INFO - Downloading/unpacking blobuploader==1.2.4 12:04:55 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 12:04:55 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 12:04:55 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 12:04:55 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 12:04:55 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 12:04:55 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 12:04:58 INFO - Downloading blobuploader-1.2.4.tar.gz 12:04:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:04:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:04:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:04:59 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 12:04:59 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 12:04:59 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 12:04:59 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 12:04:59 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 12:04:59 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 12:04:59 INFO - Downloading docopt-0.6.1.tar.gz 12:04:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:05:00 INFO - Installing collected packages: blobuploader, requests, docopt 12:05:00 INFO - Running setup.py install for blobuploader 12:05:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:05:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:05:00 INFO - Running setup.py install for requests 12:05:00 INFO - Running setup.py install for docopt 12:05:00 INFO - Successfully installed blobuploader requests docopt 12:05:00 INFO - Cleaning up... 12:05:00 INFO - Return code: 0 12:05:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:05:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:05:01 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')]} 12:05:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:05:01 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 0x10a895490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a80b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc888d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a9d30>, '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 0x10a50fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a1b20>, '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.0PfSq3bjQz/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.BP0r6IFkAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 12:05:01 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 12:05:01 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 12:05:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PfSq3bjQz/Render', 12:05:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:01 INFO - 'HOME': '/Users/cltbld', 12:05:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:01 INFO - 'LOGNAME': 'cltbld', 12:05:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:01 INFO - 'MOZ_NO_REMOTE': '1', 12:05:01 INFO - 'NO_EM_RESTART': '1', 12:05:01 INFO - 'PAGER': '/bin/cat', 12:05:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:01 INFO - 'PWD': '/builds/slave/test', 12:05:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:01 INFO - 'SHELL': '/bin/bash', 12:05:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BP0r6IFkAX/Listeners', 12:05:01 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 12:05:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:01 INFO - 'USER': 'cltbld', 12:05:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:01 INFO - 'XPC_FLAGS': '0x0', 12:05:01 INFO - 'XPC_SERVICE_NAME': '0', 12:05:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:05:02 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-KGpMhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:05:02 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-ZkFLk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:05:02 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-85mVPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:05:02 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-49dh_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:05:02 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-TCXlTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:05:02 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-5jdnmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:05:02 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-AYbCXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:05:02 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-HCh3W1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:05:02 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-nUAPPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:05:02 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-jPOpx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:05:03 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-YTB4PR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:05:03 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-ivILw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:05:03 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-ZxnCPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:05:03 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-MmLgE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:03 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-PgOPpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:05:03 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-zvZ1Ru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:05:03 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-LJgJVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:05:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:05:03 INFO - Running setup.py install for manifestparser 12:05:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:05:03 INFO - Running setup.py install for mozcrash 12:05:04 INFO - Running setup.py install for mozdebug 12:05:04 INFO - Running setup.py install for mozdevice 12:05:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:05:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:05:04 INFO - Running setup.py install for mozfile 12:05:04 INFO - Running setup.py install for mozhttpd 12:05:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:05:04 INFO - Running setup.py install for mozinfo 12:05:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:05:04 INFO - Running setup.py install for mozInstall 12:05:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:05:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:05:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:05:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:05:04 INFO - Running setup.py install for mozleak 12:05:05 INFO - Running setup.py install for mozlog 12:05:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:05:05 INFO - Running setup.py install for moznetwork 12:05:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:05:05 INFO - Running setup.py install for mozprocess 12:05:05 INFO - Running setup.py install for mozprofile 12:05:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:05:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:05:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:05:05 INFO - Running setup.py install for mozrunner 12:05:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:05:05 INFO - Running setup.py install for mozscreenshot 12:05:06 INFO - Running setup.py install for moztest 12:05:06 INFO - Running setup.py install for mozversion 12:05:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:05:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:05:06 INFO - Cleaning up... 12:05:06 INFO - Return code: 0 12:05:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:05:06 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 0x10a895490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a80b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc888d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a9d30>, '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 0x10a50fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a1b20>, '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.0PfSq3bjQz/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.BP0r6IFkAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 12:05:06 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 12:05:06 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 12:05:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PfSq3bjQz/Render', 12:05:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:06 INFO - 'HOME': '/Users/cltbld', 12:05:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:06 INFO - 'LOGNAME': 'cltbld', 12:05:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:06 INFO - 'MOZ_NO_REMOTE': '1', 12:05:06 INFO - 'NO_EM_RESTART': '1', 12:05:06 INFO - 'PAGER': '/bin/cat', 12:05:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:06 INFO - 'PWD': '/builds/slave/test', 12:05:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:06 INFO - 'SHELL': '/bin/bash', 12:05:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BP0r6IFkAX/Listeners', 12:05:06 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 12:05:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:06 INFO - 'USER': 'cltbld', 12:05:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:06 INFO - 'XPC_FLAGS': '0x0', 12:05:06 INFO - 'XPC_SERVICE_NAME': '0', 12:05:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:05:07 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-7t8xEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:05:07 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)) 12:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:05:07 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-3Mzq8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:05:07 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)) 12:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:05:07 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-YxgiQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:05:07 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)) 12:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:05:07 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-DlUbaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:05:07 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)) 12:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:05:07 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-5RbaFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:05:07 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)) 12:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:05:07 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-for4HI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:05:07 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)) 12:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:05:07 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-_VjMhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:05:07 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)) 12:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:05:07 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Cr3OPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:05:07 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)) 12:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:05:07 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-BlWyzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:05:07 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)) 12:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:05:07 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-39cmXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:05:08 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)) 12:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:05:08 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-P5bEXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:05:08 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)) 12:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:05:08 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-ZiQrlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:05:08 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)) 12:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:05:08 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-XVX7VI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:05:08 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)) 12:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:05:08 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-gzKqb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:05:08 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)) 12:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:08 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-dk6uvI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:08 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)) 12:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:05:08 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-eWw64x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:05:08 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)) 12:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:05:08 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-D9h8EV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:05:08 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)) 12:05:08 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)) 12:05:08 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)) 12:05:08 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)) 12:05:08 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)) 12:05:08 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)) 12:05:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:05:08 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 12:05:08 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 12:05:08 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 12:05:08 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 12:05:08 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 12:05:08 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 12:05:11 INFO - Downloading blessings-1.6.tar.gz 12:05:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:05:11 INFO - Installing collected packages: blessings 12:05:11 INFO - Running setup.py install for blessings 12:05:12 INFO - Successfully installed blessings 12:05:12 INFO - Cleaning up... 12:05:12 INFO - Return code: 0 12:05:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:05:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:05:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:05:12 INFO - Reading from file tmpfile_stdout 12:05:12 INFO - Current package versions: 12:05:12 INFO - blessings == 1.6 12:05:12 INFO - blobuploader == 1.2.4 12:05:12 INFO - docopt == 0.6.1 12:05:12 INFO - functools32 == 3.2.3-2 12:05:12 INFO - jsonschema == 2.5.1 12:05:12 INFO - manifestparser == 1.1 12:05:12 INFO - mozInstall == 1.12 12:05:12 INFO - mozcrash == 1.0 12:05:12 INFO - mozdebug == 0.1 12:05:12 INFO - mozdevice == 0.48 12:05:12 INFO - mozfile == 1.2 12:05:12 INFO - mozhttpd == 0.7 12:05:12 INFO - mozinfo == 0.9 12:05:12 INFO - mozleak == 0.1 12:05:12 INFO - mozlog == 3.4 12:05:12 INFO - moznetwork == 0.27 12:05:12 INFO - mozprocess == 0.23 12:05:12 INFO - mozprofile == 0.28 12:05:12 INFO - mozrunner == 6.13 12:05:12 INFO - mozscreenshot == 0.1 12:05:12 INFO - mozsystemmonitor == 0.3 12:05:12 INFO - moztest == 0.8 12:05:12 INFO - mozversion == 1.4 12:05:12 INFO - psutil == 3.1.1 12:05:12 INFO - requests == 1.2.3 12:05:12 INFO - wsgiref == 0.1.2 12:05:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:05:12 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')]} 12:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:05:12 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')]} 12:05:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:05:12 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 0x10a895490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a80b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc888d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a9d30>, '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 0x10a50fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a1b20>, '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.0PfSq3bjQz/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.BP0r6IFkAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 12:05:12 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 12:05:12 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 12:05:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PfSq3bjQz/Render', 12:05:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:12 INFO - 'HOME': '/Users/cltbld', 12:05:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:12 INFO - 'LOGNAME': 'cltbld', 12:05:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:12 INFO - 'MOZ_NO_REMOTE': '1', 12:05:12 INFO - 'NO_EM_RESTART': '1', 12:05:12 INFO - 'PAGER': '/bin/cat', 12:05:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:12 INFO - 'PWD': '/builds/slave/test', 12:05:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:12 INFO - 'SHELL': '/bin/bash', 12:05:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BP0r6IFkAX/Listeners', 12:05:12 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 12:05:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:12 INFO - 'USER': 'cltbld', 12:05:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:12 INFO - 'XPC_FLAGS': '0x0', 12:05:12 INFO - 'XPC_SERVICE_NAME': '0', 12:05:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:12 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)) 12:05:12 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)) 12:05:12 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)) 12:05:12 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)) 12:05:12 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)) 12:05:12 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)) 12:05:12 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)) 12:05:12 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)) 12:05:12 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)) 12:05:12 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)) 12:05:12 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)) 12:05:12 INFO - Cleaning up... 12:05:12 INFO - Return code: 0 12:05:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:05:12 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')]} 12:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:05:12 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')]} 12:05:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:05:12 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 0x10a895490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a80b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc888d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a9d30>, '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 0x10a50fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a1b20>, '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.0PfSq3bjQz/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.BP0r6IFkAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 12:05:12 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 12:05:12 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 12:05:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PfSq3bjQz/Render', 12:05:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:12 INFO - 'HOME': '/Users/cltbld', 12:05:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:12 INFO - 'LOGNAME': 'cltbld', 12:05:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:12 INFO - 'MOZ_NO_REMOTE': '1', 12:05:12 INFO - 'NO_EM_RESTART': '1', 12:05:12 INFO - 'PAGER': '/bin/cat', 12:05:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:12 INFO - 'PWD': '/builds/slave/test', 12:05:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:12 INFO - 'SHELL': '/bin/bash', 12:05:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BP0r6IFkAX/Listeners', 12:05:12 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 12:05:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:12 INFO - 'USER': 'cltbld', 12:05:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:12 INFO - 'XPC_FLAGS': '0x0', 12:05:12 INFO - 'XPC_SERVICE_NAME': '0', 12:05:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:05:13 INFO - Cleaning up... 12:05:13 INFO - Return code: 0 12:05:13 INFO - Running post-action listener: _resource_record_post_action 12:05:13 INFO - Running post-action listener: _start_resource_monitoring 12:05:13 INFO - Starting resource monitoring. 12:05:13 INFO - [mozharness: 2017-02-02 20:05:13.056443Z] Finished create-virtualenv step (success) 12:05:13 INFO - [mozharness: 2017-02-02 20:05:13.057424Z] Running install step. 12:05:13 INFO - Running pre-action listener: _resource_record_pre_action 12:05:13 INFO - Running main action method: install 12:05:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:05:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:05:13 INFO - Reading from file tmpfile_stdout 12:05:13 INFO - Detecting whether we're running mozinstall >=1.0... 12:05:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:05:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:05:13 INFO - Reading from file tmpfile_stdout 12:05:13 INFO - Output received: 12:05:13 INFO - Usage: mozinstall [options] installer 12:05:13 INFO - Options: 12:05:13 INFO - -h, --help show this help message and exit 12:05:13 INFO - -d DEST, --destination=DEST 12:05:13 INFO - Directory to install application into. [default: 12:05:13 INFO - "/builds/slave/test"] 12:05:13 INFO - --app=APP Application being installed. [default: firefox] 12:05:13 INFO - mkdir: /builds/slave/test/build/application 12:05:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:05:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 12:05:34 INFO - Reading from file tmpfile_stdout 12:05:34 INFO - Output received: 12:05:34 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 12:05:34 INFO - Running post-action listener: _resource_record_post_action 12:05:34 INFO - [mozharness: 2017-02-02 20:05:34.731212Z] Finished install step (success) 12:05:34 INFO - [mozharness: 2017-02-02 20:05:34.731355Z] Running run-tests step. 12:05:34 INFO - Running pre-action listener: _resource_record_pre_action 12:05:34 INFO - Running main action method: run_tests 12:05:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:05:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:05:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:05:34 INFO - 2017-02-02 12:05:34.745 screenresolution[1621:7315] starting screenresolution argv=screenresolution get 12:05:34 INFO - 2017-02-02 12:05:34.748 screenresolution[1621:7315] Display 0: 1600x1200x32@60 12:05:34 INFO - 2017-02-02 12:05:34.754 screenresolution[1622:7318] starting screenresolution argv=screenresolution list 12:05:34 INFO - Available Modes on Display 0 12:05:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:05:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:05:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:05:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:05:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:05:34 INFO - Intel Iris: 12:05:34 INFO - Chipset Model: Intel Iris 12:05:34 INFO - Type: GPU 12:05:34 INFO - Bus: Built-In 12:05:34 INFO - VRAM (Dynamic, Max): 1536 MB 12:05:34 INFO - Vendor: Intel (0x8086) 12:05:34 INFO - Device ID: 0x0a2e 12:05:34 INFO - Revision ID: 0x0009 12:05:34 INFO - Displays: 12:05:34 INFO - Display: 12:05:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:05:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:05:34 INFO - Display Serial Number: 1600x1200 60 12:05:34 INFO - Main Display: Yes 12:05:34 INFO - Mirror: Off 12:05:34 INFO - Online: Yes 12:05:34 INFO - Rotation: Supported 12:05:34 INFO - Return code: 0 12:05:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:05:34 INFO - Python 2.7.3 12:05:34 INFO - Return code: 0 12:05:34 INFO - grabbing minidump binary from tooltool 12:05:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:34 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a9d30>, '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 0x10a50fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6a1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:05:34 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:05:34 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:05:34 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 12:05:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:05:35 INFO - Return code: 0 12:05:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:05:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:05:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:05:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:05:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:05:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0135', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0135 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:05:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PfSq3bjQz/Render', 12:05:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:35 INFO - 'HOME': '/Users/cltbld', 12:05:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:35 INFO - 'LOGNAME': 'cltbld', 12:05:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:05:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:35 INFO - 'MOZ_NO_REMOTE': '1', 12:05:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:05:35 INFO - 'NO_EM_RESTART': '1', 12:05:35 INFO - 'PAGER': '/bin/cat', 12:05:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:35 INFO - 'PWD': '/builds/slave/test', 12:05:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:05:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:35 INFO - 'SHELL': '/bin/bash', 12:05:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BP0r6IFkAX/Listeners', 12:05:35 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 12:05:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:35 INFO - 'USER': 'cltbld', 12:05:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:35 INFO - 'XPC_FLAGS': '0x0', 12:05:35 INFO - 'XPC_SERVICE_NAME': '0', 12:05:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0135', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:05:35 INFO - mozversion application_buildid: 20170202101509 12:05:35 INFO - mozversion application_changeset: d171c36d484800b1bb00db1612460a7120dd2fdf 12:05:35 INFO - mozversion application_display_name: Firefox 12:05:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:05:35 INFO - mozversion application_name: Firefox 12:05:35 INFO - mozversion application_remotingname: firefox 12:05:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:05:35 INFO - mozversion application_vendor: Mozilla 12:05:35 INFO - mozversion application_version: 52.0 12:05:35 INFO - mozversion platform_buildid: 20170202101509 12:05:35 INFO - mozversion platform_changeset: d171c36d484800b1bb00db1612460a7120dd2fdf 12:05:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:05:35 INFO - mozversion platform_version: 52.0 12:05:35 INFO - using testdate: 1486065935 12:05:35 INFO - actual date: 1486065935 12:05:35 INFO - starting webserver on 'localhost:49235' 12:05:35 INFO - SUITE-START | Running 2 tests 12:05:35 INFO - TEST-START | dromaeo_css 12:05:35 INFO - operating with platform_type : mac_ 12:05:35 INFO - Initialising browser for dromaeo_css test... 12:05:35 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpGHY4DG/profile http://localhost:49235/getInfo.html) 12:05:38 INFO - PROCESS | 1632 | 2017-02-02 12:05:38.371 plugin-container[1635:7491] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c37, name = 'com.apple.tsm.portname' 12:05:38 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:05:38 INFO - PROCESS | 1632 | 2017-02-02 12:05:38.372 plugin-container[1635:7491] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b2b, name = 'com.apple.CFPasteboardClient' 12:05:38 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:05:38 INFO - PROCESS | 1632 | 2017-02-02 12:05:38.372 plugin-container[1635:7491] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:05:38 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 12:05:38 INFO - PROCESS | 1632 | colorDepth:24 12:05:38 INFO - PROCESS | 1632 | Browser inner width/height: 1024/663 12:05:38 INFO - PROCESS | 1632 | __metrics 12:05:38 ERROR - PROCESS | 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 12:05:39 INFO - TEST-INFO | 1632: exit 0 12:05:39 INFO - Browser initialized. 12:05:39 INFO - Running cycle 1/1 for dromaeo_css test... 12:05:39 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpGHY4DG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:05:41 INFO - PROCESS | 1636 | 2017-02-02 12:05:41.768 plugin-container[1637:7630] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 12:05:41 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:05:41 INFO - PROCESS | 1636 | 2017-02-02 12:05:41.768 plugin-container[1637:7630] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 12:05:41 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:05:41 INFO - PROCESS | 1636 | 2017-02-02 12:05:41.768 plugin-container[1637:7630] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:05:51 INFO - PROCESS | 1636 | RSS: Main: 188051456 12:05:51 INFO - PROCESS | 1636 | 12:08:53 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 12:08:55 INFO - PROCESS | 1636 | RSS: Main: 192884736 12:08:55 INFO - PROCESS | 1636 | 12:11:57 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 12:11:58 INFO - PROCESS | 1636 | RSS: Main: 200818688 12:11:58 INFO - PROCESS | 1636 | 12:14:59 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 12:15:00 INFO - PROCESS | 1636 | RSS: Main: 203702272 12:15:00 INFO - PROCESS | 1636 | 12:18:02 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 12:18:03 INFO - PROCESS | 1636 | RSS: Main: 207273984 12:18:03 INFO - PROCESS | 1636 | 12:21:05 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 12:21:06 INFO - PROCESS | 1636 | RSS: Main: 209264640 12:21:06 INFO - PROCESS | 1636 | 12:24:09 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 12:24:09 INFO - PROCESS | 1636 | RSS: Main: 209645568 12:24:09 INFO - PROCESS | 1636 | 12:24:09 INFO - PROCESS | 1636 | __start_tp_report 12:24:09 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 12:24:09 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 12:24:09 INFO - PROCESS | 1636 | |i|pagename|runs| 12:24:09 INFO - PROCESS | 1636 | |0;dojo.html;3532.4524698671707;3545.3618348697223;3560.4837298591706;3576.4098923677593;3593.4969104325382;4325.178216138931;5828.708564571775;5875.823725288237;5903.321118071418;5926.9407305926925;5875.647461152334;5931.911021334683;5952.761889524414;5961.940380596193;5968.2838059432825;5327.147656374981;5432.049391356497;5435.130379139338;5483.588730845183;5492.588055895804;5471.50756431921;5484.890302193954;5488.725563721818;5489.972550137264;5502.862428439281;5325.7869685212545;5369.087255166612;5478.342598888119;5484.533814625762;5520.723963801814;5358.65168764031;5373.462653734634;5430.972845135789;5458.91811622822;5508.807191748269;5323.121684921984;5396.460353964611;5411.458854114597;5412.107002344608;5431.945680543184;5718.285214348207;5918.940810591925;5954.821355359346;5996.760129594811;6006.399360064002;6184.96907515464;6198.194234749454;6208.255009398856;6251.4061164189325;6300.684965751694;6250.874982500324;6276.686165691697;6290.056491526308;6290.591111577733;6307.0539419087045;5938.435848594377;5944.197533333031;5966.880662386728;5975.312839023524;5988.37122102173;6206.379362063848;6208.286047104551;6295.307516173218;6315.873682526324;6354;6242.595416031357;6298.590591611623;6306.369363063749;6320.115183874262;6326.715297811609;6307.400796924377;6312.621242725451;6319.652419116901;6326.557141000086;6330.177076979963;4562.840300589463;6146.692665366714;6208.782692605737;6225.28409232935;6237.532185086136;5229.6339256251695;6235.345288744707;6281.371862813773;6285.874282514415;6288.276848162428;5061.5950723942;5066.16408292635;5068.847934561969;5076.035553244872;5088.109580823341;3806.352920003606;4409.294512877924;4469.932951005738;4475.1049790042125;4548.977255113703;5945.2865656120675;6064.272287325549;6086.421789929949;6111.5309653553995;6112.480439162632;5184.7926082956255;6304.432601065926;6312.989921612611;6323.019931910561;6326.557141000086;5950.434708702666;6039.184710064085;6042.546808989343;6098.237720284965;6110.114033465123;5581.162825576196;5589.888202236014;5611.2144299798065;5615.045442274742;5618.9604923089355;6562.50781191403;6592.934070659232;6602.1747281589805;6621.86756264872;6628.734850605922;5527.861803454945;6028.939710602838;6147.262328520606;6233.719482623203;6304.7162877671535;6485.967570162119;6600.702968366531;6614.66926653675;6630.701618427279;6632.801015969336;6243.500519958483;6244.250689917239;6248.0003199487865;6253.437190652863;6267.435930766253;6114.723190878713;6211.2857021441305;6299.496040316855;6328.715207815567;6351.428371446592;6077.058056001327;6144.539159562962;6230.470410015199;6245.8750824982335;6292.276388215414;6239.469645080219;6260.123582698517;6284.4344009039405;6298.401651843067;6307.936920630735;5126.282320475062;6008.128821320972;6040.728167232573;6172.444479996821;6213.099100630293;6143.72682107623;6172.876542469216;6178;6229;6301.936980630318;6155.1075094112;6158.969205154125;6165.10605962124;6168.444839964424;6172.814815555541 12:24:09 INFO - PROCESS | 1636 | |1;ext.html;497.935268415106;508.87532554524137;520.1443625236484;542.4439949052221;555.466751918159;39392;41101.97245068871;41165.38251926223;41237.79381103094;41242.96892577687;42203.155936881245;42548.57451425485;42683.14633707324;42764.14471710564;42937.35593966093;9574.28192885533;9763.609455621767;9764.365316254421;9771.071748183911;9775.217982561378;9765.365251258681;9809.411435313905;9811.411315321067;9845.310828242025;9873.16078133356;9731.318807683465;9809.754756131118;9842;9862.753431164207;9893.752656183617;9755.853662195073;9764.218862490983;9825.213982881352;9836.90163098367;9876.111149996463;9409.435433873921;9460.90539094614;9544.665936692252;9563.808723825485;9580.233581313549;40798.79600602008;41006.38490422646;41052.589474105174;41155;41163.97090072772;3380.4591265397416;3387.5088112223834;3388.254583991518;3582.6417358264225;3586.7847929124337;28874.422511549652;30036.24909377283;30289;31902.840485797653;32144.03567892967;3090.7945901098587;3298.422776014188;3369.0566641340465;3372.7301815854917;3484.0593039879345;414005.9299703512;497773;498147;501999.4900025476;506011;45849;58858;58883.99999999914;59101;60362.69818650967;58303.8339233213;59863;60859.69570152121;60981.390186098455;61050.38949610447;14217.5782421757;16382.508524744277;16393.934394264335;16538.58653533647;16670;12854.971602271797;12964.675883102998;12983.935080324538;12996.48014079445;13178.143420677625;15248.923755381153;15475.226238688021;15800.130992795277;15816.60458488547;15835.920820395824;6093.908591371133;6121.816345509642;6182.443580077815;6226.968865155736;6255.530835187379;857.9227869491775;1003.6035765872585;1010.1767059846202;1012.3116280928891;1064.6007747094839;27156.86421567919;30240.69759302379;30337;30946.381072378426;31045.379092418025;987.4766373821886;1106.336198281041;1127.9548818047351;1201.1351826684772;1227.0551675210218;13939.860601394059;14314;16653;16717.414890478776;16837;688814;694031;694256;696023;696850;58214;58258.708706457044;58554.414455855745;58631.41368586259;58784.82430351369;1390.242317936727;1396.7765157574738;1414.1515090945556;1416.1290806154348;1416.6883285677134;30662.386752265276;30896.536551952187;31549.89575364851;32127.67872321247;32329.19177020499;55207.44792552023;56908;57027;59188;59917.700411497666;628525;629962;636425;636874;637878;26783;42434;42571.57428425676;43031.569684302754;43037.56962430461;43979.34030989602;44884.77557612191;45326.77336613428;45966;46214.53785462103;37641.87074387773;37773.24453510969;38798.2240355197;39898.601013989486;39949;38207.617923820406;38421.6157838418;39252.607473924894;39285.41071883867;39688.206235875696;37432.81283593673;38305.6169438302;38543.42184867285;39110.6088939107;39917.600823991386;32791;34094.82952585221;34236.657633424344;34741.305173896886;34839.65160348364;24656.39234288317;25668.87165564235;25859.741402586485;25919.352016199748;26213.95144194287 12:24:09 INFO - PROCESS | 1636 | |2;jquery.html;347.3556552594936;359.08791669160337;372.9160938788772;387.55237700455984;410.64896490546107;15875.761863572041;19241.711374329378;20434.89782551089;20629.793702062994;20887.582248355026;10875.293105948127;11784.116191285644;19263.807361926378;19885.602287954232;19929;5370.489803468664;5599.41206173352;5606.187102870082;5612.382637909847;5627.634203776762;4575.931361029588;4587.63298936086;5465.289512363387;6867.793966181022;6934.514583979124;6648.202215734119;6808.353206445405;6842.55523390978;6872.450203983669;6951.513394062417;6699.263081061079;6828.487863410214;6831.2144103428245;6831.726730930757;6831.829204269883;5299.54953828925;5579.69311687857;5635.6055076144685;5989.251343582052;6071.939280607182;13136.752008559171;13173.736525269536;13364.198148111047;13400.93299533496;13602.727945441135;2315.7336906255828;2331.463763334443;2427.9393015174587;2865.1834227245345;2961.2300801791475;9869.259805514615;10021.649242276486;11005;15357.539273821803;15747.055176689435;1845.2065611786927;1896.781870084944;1924.7177411353805;1970.714246434259;1981.2075169931998;115636.42181789121;121038.18442723366;127494.72505274921;128469;130001.69998299991;11644.53421863116;11891.28652280866;11941.402929853646;11943.761124777457;11970.640880773417;14937.730292925005;14943.925280373529;15030.32363543643;15074.246287685572;15101.697966040838;8602.741917742353;8752.781180470412;8753;8796.560171991376;8826.338024648177;7177.605231712201;7469.663865126083;8750.124987501325;8759.868601970975;8768.693095741619;4586.495485496593;4620.3993480847275;4641.2109941310055;4699.976500117477;4703.64722645803;1763.515033365809;1948.4252145617077;2008.9296874609317;2021.2016253579754;2047.5495391013953;791.6239786101555;882.8719835623799;914.711865762296;967.4969016111583;1032.8554002439664;19703.40889773309;20655.69016464754;20672.276470323766;20744;20893;929.2101713543434;948.1321796061748;967.8257913575483;1012.7822518158631;1016.7356487313203;10403.595514605431;10960.506777195045;10966.835497467544;10988;11059.281146725598;602380;609630;615974;627230;629284;91130.54434727784;92579.53710231406;107023.46488267665;107994.46002769936;109568;975.6341371985505;1005.4269066632096;1009.4902074452355;1025.5282570017725;1028.665683652801;17552.82447175563;17995.910020449814;18346.990915499777;18375.805572637735;18679.066046697884;51857.96284074373;52808;53129;53207;53400.73299633477;72097;73916.89124663027;74785.6260718693;75961;79294;5216.686998780086;7171.175314838861;7480.925190748023;7572.318491335806;7848.136704962335;10635.787284254426;12293.815592766294;12873.35633218354;12908.096433249582;12960;5388.164834450667;8379.743038544024;9326.487043212688;10380.792384152426;10665.840012399975;6332.968335158294;9877.802443950937;10688.076146293432;10810.729731756768;11513.021393181674;11122.61070862516;11141;11486.36824974601;11642.359670218219;11698.883011169779;7785.260400261966;8242.587870606565;8289.917100828914;8325.333973282244;8348.916510834813;8145.551994640352;10233.181345492194;10735.087517561095;10758.193135514712;11535.134864885 12:24:09 INFO - PROCESS | 1636 | |3;mootools.html;1450.7026059657771;1502.6619010722586;1716.3821024431202;1843.5022543913153;1884.6984482482796;6509.544331896769;7783.727569535067;8282.958585207072;8726.52004139772;8734;8271.83456330873;8543.27382172516;8550.70072547461;8637.956810215956;8646.654133834654;2218.7781221877844;2271.738750043741;2389.892454839532;2421.443068094339;2429.2469334506272;2414.9034038638433;2437.2322718343667;2440.694913135858;2455.607102863542;2457.8033757299463;2030.7055476955832;2375.489269806991;2397.9640305395346;2402.1112188490197;2420.177139772477;2374.833761636686;2383.2135395319588;2390.4501964548253;2397.6043952747864;2403.42317843718;2409.421738782697;2422.454947636785;2436.9634455483183;2444.4255599934;2444.706635203769;7410.740624078131;8566.871496927552;8643.43817651857;8675.739727808175;8677.869831952463;5256.789728410859;5393.325834270716;5413.702246376448;5423.837284881421;5430.0768869292315;3347.8828241011447;3348.79907205566;3504.4743288506675;3594.388953877847;3656.360136976045;1036.642358386363;1080.7730376620918;1086.8532748078987;1126.7239526316105;1143.6168883424064;11232.831507527393;11237.213395062348;11251.381174035425;11255.099592032619;11286.774264514665;4586.2661974083985;4736.95263047372;4802.495737947535;4818.963922756624;4880;9650.662226822027;9653.17947974416;9667.274954378308;9677.080677335642;9733.9026609733;5320.361556613231;5356.250124982505;5365.034293827149;5365.731713414314;5384.461553844662;4444.711093778894;4485.349624304457;4875.512448755097;4923.310736496894;4967.056259310792;3921.745086569364;3966.900827479336;4016.397540368968;4056.1887622475624;4065.4308396824467;1307;1310.462710288777;1312.8949684025256;1318.4726109556063;1325.337331334333;807.3904202327205;886.9822603547893;902.5848109869534;912.3613470570627;918.2975024106564;5660.2075709400715;5697.715114244271;5716.799912003143;5719.771209151588;5798.623089499253;950.8003319303024;960.7261930349816;962.2013728605241;962.4562122400821;1021.8058568871891;3636.309101270803;3698.0939669780537;3718.3121122592406;3765.284595926765;3809.1239015026144;10125.696229112991;10471.633492827714;10471.738206544745;10482.21383396233;10499.160067194605;8812.735617931472;10223.642172523925;10428.5828566858;10446.68659940203;10449.425281609585;7730.458867879307;8042.879356809653;8050.396220283385;8225.506469611842;8233.711820086268;5410.161424979135;5449.700266485382;5630.718464076862;5694.430556944272;5706.771729130788;7870.212978702084;9432.245420366216;9590.08894155054;9686.02810113211;9692.70921872345;9633.229341652792;9683.983181766094;9839.606415743576;9941.005899410002;9959.800803983735;6315.747370105144;6330.651814150082;6952;7105.3960413363375;7156.677949492181;8023.879641805261;8063.959680201796;8074.313683336982;8140.674373025013;8141.511509309224;7153.6423178839805;7180.640967951686;7339.155997060407;7367.742129025459;7413.592252426169;7088.716451341888;7266.273372662691;7274.272572742683;7300.780976570712;7317.8902316466365;5694;6637.137172167587;6644.800655980328;6645.9003114954285;6665.566738162004;4637.956459796519;4674.462436819809;4713.67004309695;4744.525547445228;4751.311059896365;4423.960369313145;4771;4879.243717223836;5025.824096156617;5071 12:24:09 INFO - PROCESS | 1636 | |4;prototype.html;297.24203281631833;308.80236648544934;309.9101260634416;316.9857356418961;321.63012535584073;5409.323834520685;5505.284313039304;5516.889662206753;5564.944350556493;5601;4503.77481125944;5224.63427560071;5315.468453154683;5408.513233808956;5500.717202361026;3530.5939816920995;3711.480392745018;3830.6743926766258;3958.3864501002245;4211.599898009684;3353.899383018513;3797.506324177853;4210.915781684364;4223.61987421132;4227.767472788995;4106.568810274923;4140.337545992642;4147.854825081123;4179.561146079664;4198.992241861946;3667.8812962046563;4129.009037830929;4130.586941305861;4150.605692459211;4156.210320039183;3300.5379246905454;3376.902506685337;3503.754737168399;3550.414181660023;3559.2238054317672;5308.283381743454;5315.867103322409;5317.335333083364;5367.570594352443;5374.97875403676;4614.884627884297;4718.339432479455;4720.173969555314;4728.912350159482;4763.785629646674;4995.500449955011;5408.540274076709;5669.773209071632;5672.631278966854;5702.486776190121;3903.1608204236227;3929.9017524561827;3935.783531905744;3947.4670919426085;3951.4270430787374;5283.577313814885;5943.3745415296635;6039.939600604026;6056.21269235001;6100.389961003864;3661.7802931823644;3697.7226707997006;3940.9014774630864;3970.828605561366;4035.8183881725395;3407.216340241758;3793.962060379416;3813.5995720448955;4007.318755811519;4033.5361433434946;3533.8338348344982;4451.772464962876;4675.929861052087;4695.319178719066;4715.929261061086;4841.95158048422;5281.445448227958;5566.248556444909;5654.491095801398;5819.719661674425;1199.0767109325946;1230.6308107567693;1291.4188615123232;1320.4124164746788;1333.973320533584;797.365400928095;824.8864033554653;840.8234270803077;869.9391042626955;900.4957223954607;4163.687723420756;4488.977555112268;4521.965115697883;5188.195829646411;5286.418493965661;5025.246213068069;5106.744662766847;5595.972020139873;5635.6618602883955;5657.066584013674;4425.026494171278;5126.820561280338;5147.613928955268;5179.452075328337;5261;2938.206684195255;2998.250437390652;3026.1856473479875;3038.95156171125;3051.908442746721;5245.947540524622;5405.891882162414;6018.21763170785;6060.575759696779;6074.51403887688;5171.3535808024;5267.0256002639635;5333.786648534093;5481.5296284259375;5594.104943209067;5789.623674461147;5880.500157486576;5996.250468691326;6055.545834062463;6072.271327440735;4620.930686039779;4857.757112144308;5322.38792538863;5353.839384818461;5669.659820410789;4942.1598328285;5270.130428479258;5442.9930462864495;5596.888062238651;5688.601797874275;5069.493050694901;5935.643861368331;5950.910736339045;5965.701714914324;5969.701514924323;3283.31050479402;3749.531308586427;3780.6597406233573;3787.5833658297015;3826.9617303826603;5002.899942001212;5178.870528236674;5919.852003699942;5948.851278718067;6030.703398769286;5481.260029895913;5608;5690.691141037667;5709.885802284014;5792.507636850746;4870.95129048705;5531.3362396512675;5752.28096487939;5752.798652047326;5759.617691754032;4582.289745089516;4800.375951126471;5179.4779549906425;5651.5415794129685;5677.971610141923;5293.788248470172;5777.422257774357;5778.739956701875;5821.70891455417;5838.970805145947;5628.577856660685;5662.915056274077;5672.893785711746;5686.317641883001;5697.800576979786 12:24:09 INFO - PROCESS | 1636 | |5;yui.html;627.3945642455031;742.5322047110321;755.66372964031;761.3490465651864;786.3080489169533;14361.35373908174;15045.699086018287;15080.924595377035;15116.39534418622;15193.848061519382;13881.930590347034;14054.64863378416;14152.575422737309;14200.644983875409;14229.501967431142;680.9421199198061;847.9152084791533;850.3409857360564;869.4652788535037;878.1306506558525;854.5599016506504;860.2601762484259;861.3066481482405;861.4658911474894;864.6714248585529;923.224491427201;927.2211342472322;927.717046300878;929.5305870535404;932.7994481186544;760.7413479417024;900.2168113741054;907.773056735816;917.5091326140483;918.9540522973858;679.4835924697217;681.417388133149;681.9736296873224;682.1234713393266;683.4429939599261;12449.502019919193;12484.375781210903;12562.623121306284;12606.180598261175;12656.74686506274;13965.3715582798;14024;14043.297835108204;14085.225312607803;14101.78847317291;11106.888931110667;11527.423628818526;11548.826767598493;11701.414929253588;12214.083943704258;3622.275544891006;3721.9627803721887;3798.5441746990264;3813.48517950076;3855.094052897567;77545.22454775493;83237.58381208115;90132;93959.12081758327;94064.52967735186;9682;10872.836907446395;11158.330500170016;11183.72040698989;11335.149863760085;20721.067551959895;20746.58506829855;21002;21189.78810211909;21204.575908481744;8022.438429310009;8041.7989550260545;8083.636236369378;8105.27052565272;8140.552269625109;4535.478419981679;4575.153596584642;4576.702514336557;4584.679072464895;4587.8623641290815;3441.6042155152477;3550.55618047744;3555.253396786652;3739.476473293741;3909.3940439231965;631.703099543214;644.1336402538578;712.8966299886488;720.7873677265219;724.6376811594203;601.7743346245159;602.665096810558;657.4707360574688;662.9624637442387;667.9532432729662;9079.364444488954;9267.536623168815;9302.813733819672;9482.668106616235;9737.618071867688;635.4304866978556;658.3153520338794;659.581165959619;662.5262937000068;675.9121314229119;3539.7876127432437;3827.6937844972335;4104.610062044101;4115.629593336614;4681.227597446451;58492;75079.62460187664;75563;112775;126858;65430;67844.6607766958;84430.57784711037;85005.14994850096;87605;63066.68466657729;64832;64947.02579461312;70631.94052089223;80393;9131.726048218565;9365.250779937453;9913.432835820895;9923.206143508358;10081.848772268275;40096.59903400928;52995.73502132465;55322.17016744833;57576;58650.70674646599;56394.71802641125;65078.674606626664;77467.61266193633;78249.82625260511;81570.73717051488;12950.287734174712;13167.60497185086;13220.2152709383;13391.598252052454;13511.324433778074;14754;14813.185274809988;15078.0199287046;15079.924600377366;15250;12032.571901531292;12058.276503409823;12058.577949771716;12168.57409990646;12317.44571494267;13303;13495;13757.96815238881;13828.239446830119;14199.929000355345;13196.60410187696;13348.199108053548;13710.0402971791;13723.725525489235;14435.855641443452;9746.658866939622;9839.5080245986;11210.327380357205;11538.94230528842;11550.59572914944;11454.427278635869;11468.770624587629;11551.711207219887;11619.244749091282;11754.177207595387 12:24:09 INFO - PROCESS | 1636 | __end_tp_report 12:24:09 INFO - PROCESS | 1636 | __start_cc_report 12:24:09 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,365 12:24:09 INFO - PROCESS | 1636 | __end_cc_report 12:24:09 INFO - PROCESS | 1636 | __startTimestamp1486067049277__endTimestamp 12:24:09 INFO - PROCESS | 1636 | 12:24:09 INFO - PROCESS | 1636 | ------- Summary: start ------- 12:24:09 INFO - PROCESS | 1636 | Number of tests: 6 12:24:09 INFO - PROCESS | 1636 | 12:24:09 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5854.88 Median:6111.53 stddev:649.98 (10.6%) stddev-sans-first:625.92 12:24:09 INFO - PROCESS | 1636 | Values: 3532.5 3545.4 3560.5 3576.4 3593.5 4325.2 5828.7 5875.8 5903.3 5926.9 5875.6 5931.9 5952.8 5961.9 5968.3 5327.1 5432.0 5435.1 5483.6 5492.6 5471.5 5484.9 5488.7 5490.0 5502.9 5325.8 5369.1 5478.3 5484.5 5520.7 5358.7 5373.5 5431.0 5458.9 5508.8 5323.1 5396.5 5411.5 5412.1 5431.9 5718.3 5918.9 5954.8 5996.8 6006.4 6185.0 6198.2 6208.3 6251.4 6300.7 6250.9 6276.7 6290.1 6290.6 6307.1 5938.4 5944.2 5966.9 5975.3 5988.4 6206.4 6208.3 6295.3 6315.9 6354.0 6242.6 6298.6 6306.4 6320.1 6326.7 6307.4 6312.6 6319.7 6326.6 6330.2 4562.8 6146.7 6208.8 6225.3 6237.5 5229.6 6235.3 6281.4 6285.9 6288.3 5061.6 5066.2 5068.8 5076.0 5088.1 3806.4 4409.3 4469.9 4475.1 4549.0 5945.3 6064.3 6086.4 6111.5 6112.5 5184.8 6304.4 6313.0 6323.0 6326.6 5950.4 6039.2 6042.5 6098.2 6110.1 5581.2 5589.9 5611.2 5615.0 5619.0 6562.5 6592.9 6602.2 6621.9 6628.7 5527.9 6028.9 6147.3 6233.7 6304.7 6486.0 6600.7 6614.7 6630.7 6632.8 6243.5 6244.3 6248.0 6253.4 6267.4 6114.7 6211.3 6299.5 6328.7 6351.4 6077.1 6144.5 6230.5 6245.9 6292.3 6239.5 6260.1 6284.4 6298.4 6307.9 5126.3 6008.1 6040.7 6172.4 6213.1 6143.7 6172.9 6178.0 6229.0 6301.9 6155.1 6159.0 6165.1 6168.4 6172.8 12:24:09 INFO - PROCESS | 1636 | 12:24:09 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:73562.53 Median:41164.68 stddev:163486.78 (397.2%) stddev-sans-first:163853.37 12:24:09 INFO - PROCESS | 1636 | Values: 497.9 508.9 520.1 542.4 555.5 39392.0 41102.0 41165.4 41237.8 41243.0 42203.2 42548.6 42683.1 42764.1 42937.4 9574.3 9763.6 9764.4 9771.1 9775.2 9765.4 9809.4 9811.4 9845.3 9873.2 9731.3 9809.8 9842.0 9862.8 9893.8 9755.9 9764.2 9825.2 9836.9 9876.1 9409.4 9460.9 9544.7 9563.8 9580.2 40798.8 41006.4 41052.6 41155.0 41164.0 3380.5 3387.5 3388.3 3582.6 3586.8 28874.4 30036.2 30289.0 31902.8 32144.0 3090.8 3298.4 3369.1 3372.7 3484.1 414005.9 497773.0 498147.0 501999.5 506011.0 45849.0 58858.0 58884.0 59101.0 60362.7 58303.8 59863.0 60859.7 60981.4 61050.4 14217.6 16382.5 16393.9 16538.6 16670.0 12855.0 12964.7 12983.9 12996.5 13178.1 15248.9 15475.2 15800.1 15816.6 15835.9 6093.9 6121.8 6182.4 6227.0 6255.5 857.9 1003.6 1010.2 1012.3 1064.6 27156.9 30240.7 30337.0 30946.4 31045.4 987.5 1106.3 1128.0 1201.1 1227.1 13939.9 14314.0 16653.0 16717.4 16837.0 688814.0 694031.0 694256.0 696023.0 696850.0 58214.0 58258.7 58554.4 58631.4 58784.8 1390.2 1396.8 1414.2 1416.1 1416.7 30662.4 30896.5 31549.9 32127.7 32329.2 55207.4 56908.0 57027.0 59188.0 59917.7 628525.0 629962.0 636425.0 636874.0 637878.0 26783.0 42434.0 42571.6 43031.6 43037.6 43979.3 44884.8 45326.8 45966.0 46214.5 37641.9 37773.2 38798.2 39898.6 39949.0 38207.6 38421.6 39252.6 39285.4 39688.2 37432.8 38305.6 38543.4 39110.6 39917.6 32791.0 34094.8 34236.7 34741.3 34839.7 24656.4 25668.9 25859.7 25919.4 26214.0 12:24:09 INFO - PROCESS | 1636 | 12:24:09 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:34418.02 Median:2646.56 stddev:102445.41 (3870.9%) stddev-sans-first:102700.85 12:24:09 INFO - PROCESS | 1636 | Values: 347.4 359.1 372.9 387.6 410.6 15875.8 19241.7 20434.9 20629.8 20887.6 10875.3 11784.1 19263.8 19885.6 19929.0 5370.5 5599.4 5606.2 5612.4 5627.6 4575.9 4587.6 5465.3 6867.8 6934.5 6648.2 6808.4 6842.6 6872.5 6951.5 6699.3 6828.5 6831.2 6831.7 6831.8 5299.5 5579.7 5635.6 5989.3 6071.9 13136.8 13173.7 13364.2 13400.9 13602.7 2315.7 2331.5 2427.9 2865.2 2961.2 9869.3 10021.6 11005.0 15357.5 15747.1 1845.2 1896.8 1924.7 1970.7 1981.2 115636.4 121038.2 127494.7 128469.0 130001.7 11644.5 11891.3 11941.4 11943.8 11970.6 14937.7 14943.9 15030.3 15074.2 15101.7 8602.7 8752.8 8753.0 8796.6 8826.3 7177.6 7469.7 8750.1 8759.9 8768.7 4586.5 4620.4 4641.2 4700.0 4703.6 1763.5 1948.4 2008.9 2021.2 2047.5 791.6 882.9 914.7 967.5 1032.9 19703.4 20655.7 20672.3 20744.0 20893.0 929.2 948.1 967.8 1012.8 1016.7 10403.6 10960.5 10966.8 10988.0 11059.3 602380.0 609630.0 615974.0 627230.0 629284.0 91130.5 92579.5 107023.5 107994.5 109568.0 975.6 1005.4 1009.5 1025.5 1028.7 17552.8 17995.9 18347.0 18375.8 18679.1 51858.0 52808.0 53129.0 53207.0 53400.7 72097.0 73916.9 74785.6 75961.0 79294.0 5216.7 7171.2 7480.9 7572.3 7848.1 10635.8 12293.8 12873.4 12908.1 12960.0 5388.2 8379.7 9326.5 10380.8 10665.8 6333.0 9877.8 10688.1 10810.7 11513.0 11122.6 11141.0 11486.4 11642.4 11698.9 7785.3 8242.6 8289.9 8325.3 8348.9 8145.6 10233.2 10735.1 10758.2 11535.1 12:24:09 INFO - PROCESS | 1636 | 12:24:09 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5541.90 Median:5288.58 stddev:3036.04 (57.4%) stddev-sans-first:3028.99 12:24:09 INFO - PROCESS | 1636 | Values: 1450.7 1502.7 1716.4 1843.5 1884.7 6509.5 7783.7 8283.0 8726.5 8734.0 8271.8 8543.3 8550.7 8638.0 8646.7 2218.8 2271.7 2389.9 2421.4 2429.2 2414.9 2437.2 2440.7 2455.6 2457.8 2030.7 2375.5 2398.0 2402.1 2420.2 2374.8 2383.2 2390.5 2397.6 2403.4 2409.4 2422.5 2437.0 2444.4 2444.7 7410.7 8566.9 8643.4 8675.7 8677.9 5256.8 5393.3 5413.7 5423.8 5430.1 3347.9 3348.8 3504.5 3594.4 3656.4 1036.6 1080.8 1086.9 1126.7 1143.6 11232.8 11237.2 11251.4 11255.1 11286.8 4586.3 4737.0 4802.5 4819.0 4880.0 9650.7 9653.2 9667.3 9677.1 9733.9 5320.4 5356.3 5365.0 5365.7 5384.5 4444.7 4485.3 4875.5 4923.3 4967.1 3921.7 3966.9 4016.4 4056.2 4065.4 1307.0 1310.5 1312.9 1318.5 1325.3 807.4 887.0 902.6 912.4 918.3 5660.2 5697.7 5716.8 5719.8 5798.6 950.8 960.7 962.2 962.5 1021.8 3636.3 3698.1 3718.3 3765.3 3809.1 10125.7 10471.6 10471.7 10482.2 10499.2 8812.7 10223.6 10428.6 10446.7 10449.4 7730.5 8042.9 8050.4 8225.5 8233.7 5410.2 5449.7 5630.7 5694.4 5706.8 7870.2 9432.2 9590.1 9686.0 9692.7 9633.2 9684.0 9839.6 9941.0 9959.8 6315.7 6330.7 6952.0 7105.4 7156.7 8023.9 8064.0 8074.3 8140.7 8141.5 7153.6 7180.6 7339.2 7367.7 7413.6 7088.7 7266.3 7274.3 7300.8 7317.9 5694.0 6637.1 6644.8 6645.9 6665.6 4638.0 4674.5 4713.7 4744.5 4751.3 4424.0 4771.0 4879.2 5025.8 5071.0 12:24:09 INFO - PROCESS | 1636 | 12:24:09 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4558.97 Median:5179.16 stddev:1410.24 (27.2%) stddev-sans-first:1377.44 12:24:09 INFO - PROCESS | 1636 | Values: 297.2 308.8 309.9 317.0 321.6 5409.3 5505.3 5516.9 5564.9 5601.0 4503.8 5224.6 5315.5 5408.5 5500.7 3530.6 3711.5 3830.7 3958.4 4211.6 3353.9 3797.5 4210.9 4223.6 4227.8 4106.6 4140.3 4147.9 4179.6 4199.0 3667.9 4129.0 4130.6 4150.6 4156.2 3300.5 3376.9 3503.8 3550.4 3559.2 5308.3 5315.9 5317.3 5367.6 5375.0 4614.9 4718.3 4720.2 4728.9 4763.8 4995.5 5408.5 5669.8 5672.6 5702.5 3903.2 3929.9 3935.8 3947.5 3951.4 5283.6 5943.4 6039.9 6056.2 6100.4 3661.8 3697.7 3940.9 3970.8 4035.8 3407.2 3794.0 3813.6 4007.3 4033.5 3533.8 4451.8 4675.9 4695.3 4715.9 4842.0 5281.4 5566.2 5654.5 5819.7 1199.1 1230.6 1291.4 1320.4 1334.0 797.4 824.9 840.8 869.9 900.5 4163.7 4489.0 4522.0 5188.2 5286.4 5025.2 5106.7 5596.0 5635.7 5657.1 4425.0 5126.8 5147.6 5179.5 5261.0 2938.2 2998.3 3026.2 3039.0 3051.9 5245.9 5405.9 6018.2 6060.6 6074.5 5171.4 5267.0 5333.8 5481.5 5594.1 5789.6 5880.5 5996.3 6055.5 6072.3 4620.9 4857.8 5322.4 5353.8 5669.7 4942.2 5270.1 5443.0 5596.9 5688.6 5069.5 5935.6 5950.9 5965.7 5969.7 3283.3 3749.5 3780.7 3787.6 3827.0 5002.9 5178.9 5919.9 5948.9 6030.7 5481.3 5608.0 5690.7 5709.9 5792.5 4871.0 5531.3 5752.3 5752.8 5759.6 4582.3 4800.4 5179.5 5651.5 5678.0 5293.8 5777.4 5778.7 5821.7 5839.0 5628.6 5662.9 5672.9 5686.3 5697.8 12:24:09 INFO - PROCESS | 1636 | 12:24:09 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:19120.34 Median:28838.48 stddev:26585.36 (92.2%) stddev-sans-first:26623.68 12:24:09 INFO - PROCESS | 1636 | Values: 627.4 742.5 755.7 761.3 786.3 14361.4 15045.7 15080.9 15116.4 15193.8 13881.9 14054.6 14152.6 14200.6 14229.5 680.9 847.9 850.3 869.5 878.1 854.6 860.3 861.3 861.5 864.7 923.2 927.2 927.7 929.5 932.8 760.7 900.2 907.8 917.5 919.0 679.5 681.4 682.0 682.1 683.4 12449.5 12484.4 12562.6 12606.2 12656.7 13965.4 14024.0 14043.3 14085.2 14101.8 11106.9 11527.4 11548.8 11701.4 12214.1 3622.3 3722.0 3798.5 3813.5 3855.1 77545.2 83237.6 90132.0 93959.1 94064.5 9682.0 10872.8 11158.3 11183.7 11335.1 20721.1 20746.6 21002.0 21189.8 21204.6 8022.4 8041.8 8083.6 8105.3 8140.6 4535.5 4575.2 4576.7 4584.7 4587.9 3441.6 3550.6 3555.3 3739.5 3909.4 631.7 644.1 712.9 720.8 724.6 601.8 602.7 657.5 663.0 668.0 9079.4 9267.5 9302.8 9482.7 9737.6 635.4 658.3 659.6 662.5 675.9 3539.8 3827.7 4104.6 4115.6 4681.2 58492.0 75079.6 75563.0 112775.0 126858.0 65430.0 67844.7 84430.6 85005.1 87605.0 63066.7 64832.0 64947.0 70631.9 80393.0 9131.7 9365.3 9913.4 9923.2 10081.8 40096.6 52995.7 55322.2 57576.0 58650.7 56394.7 65078.7 77467.6 78249.8 81570.7 12950.3 13167.6 13220.2 13391.6 13511.3 14754.0 14813.2 15078.0 15079.9 15250.0 12032.6 12058.3 12058.6 12168.6 12317.4 13303.0 13495.0 13758.0 13828.2 14199.9 13196.6 13348.2 13710.0 13723.7 14435.9 9746.7 9839.5 11210.3 11538.9 11550.6 11454.4 11468.8 11551.7 11619.2 11754.2 12:24:09 INFO - PROCESS | 1636 | -------- Summary: end -------- 12:24:09 INFO - PROCESS | 1636 | 12:24:09 INFO - TEST-INFO | 1636: exit 0 12:24:09 INFO - TEST-OK | dromaeo_css | took 1114528ms 12:24:09 INFO - TEST-START | kraken 12:24:09 INFO - operating with platform_type : mac_ 12:24:09 INFO - Initialising browser for kraken test... 12:24:09 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpwNEeZ1/profile http://localhost:49235/getInfo.html) 12:24:12 INFO - PROCESS | 1648 | 2017-02-02 12:24:12.753 plugin-container[1650:9966] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 12:24:12 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:24:12 INFO - PROCESS | 1648 | 2017-02-02 12:24:12.754 plugin-container[1650:9966] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0f, name = 'com.apple.CFPasteboardClient' 12:24:12 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:24:12 INFO - PROCESS | 1648 | 2017-02-02 12:24:12.754 plugin-container[1650:9966] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:24:12 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 12:24:12 INFO - PROCESS | 1648 | colorDepth:24 12:24:12 INFO - PROCESS | 1648 | Browser inner width/height: 1024/663 12:24:12 INFO - PROCESS | 1648 | __metrics 12:24:12 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 12:24:13 INFO - TEST-INFO | 1648: exit 0 12:24:13 INFO - Browser initialized. 12:24:13 INFO - Running cycle 1/1 for kraken test... 12:24:13 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpwNEeZ1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:24:16 INFO - PROCESS | 1651 | 2017-02-02 12:24:16.054 plugin-container[1652:10091] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x424b, name = 'com.apple.tsm.portname' 12:24:16 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:24:16 INFO - PROCESS | 1651 | 2017-02-02 12:24:16.055 plugin-container[1652:10091] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 12:24:16 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:24:16 INFO - PROCESS | 1651 | 2017-02-02 12:24:16.055 plugin-container[1652:10091] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:24:25 INFO - PROCESS | 1651 | RSS: Main: 188186624 12:24:25 INFO - PROCESS | 1651 | 12:24:28 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 12:24:29 INFO - PROCESS | 1651 | RSS: Main: 198561792 12:24:29 INFO - PROCESS | 1651 | 12:24:32 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 12:24:32 INFO - PROCESS | 1651 | RSS: Main: 206483456 12:24:32 INFO - PROCESS | 1651 | 12:24:36 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 12:24:36 INFO - PROCESS | 1651 | RSS: Main: 207376384 12:24:36 INFO - PROCESS | 1651 | 12:24:38 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 12:24:39 INFO - PROCESS | 1651 | RSS: Main: 207208448 12:24:39 INFO - PROCESS | 1651 | 12:24:41 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:24:41 INFO - PROCESS | 1651 | RSS: Main: 207233024 12:24:41 INFO - PROCESS | 1651 | 12:24:44 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 12:24:45 INFO - PROCESS | 1651 | RSS: Main: 211226624 12:24:45 INFO - PROCESS | 1651 | 12:24:48 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 12:24:48 INFO - PROCESS | 1651 | RSS: Main: 211435520 12:24:48 INFO - PROCESS | 1651 | 12:24:51 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 12:24:52 INFO - PROCESS | 1651 | RSS: Main: 210989056 12:24:52 INFO - PROCESS | 1651 | 12:24:54 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:24:54 INFO - PROCESS | 1651 | RSS: Main: 210722816 12:24:54 INFO - PROCESS | 1651 | 12:24:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:24:57 INFO - PROCESS | 1651 | RSS: Main: 210300928 12:24:57 INFO - PROCESS | 1651 | 12:24:59 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:25:00 INFO - PROCESS | 1651 | RSS: Main: 210153472 12:25:00 INFO - PROCESS | 1651 | 12:25:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:25:03 INFO - PROCESS | 1651 | RSS: Main: 210546688 12:25:03 INFO - PROCESS | 1651 | 12:25:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:25:07 INFO - PROCESS | 1651 | RSS: Main: 210391040 12:25:07 INFO - PROCESS | 1651 | 12:25:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 12:25:09 INFO - PROCESS | 1651 | RSS: Main: 211898368 12:25:09 INFO - PROCESS | 1651 | 12:25:09 INFO - PROCESS | 1651 | __start_tp_report 12:25:09 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 12:25:09 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 12:25:09 INFO - PROCESS | 1651 | |i|pagename|runs| 12:25:09 INFO - PROCESS | 1651 | |0;ai-astar;105;118;119;119;118;118;119;119;118;119 12:25:09 INFO - PROCESS | 1651 | |1;audio-beat-detection;147;113;112;143;112;113;116;115;140;112 12:25:09 INFO - PROCESS | 1651 | |2;audio-dft;195;199;281;249;196;197;195;198;202;198 12:25:09 INFO - PROCESS | 1651 | |3;audio-fft;81;59;59;82;54;59;63;87;105;84 12:25:09 INFO - PROCESS | 1651 | |4;audio-oscillator;82;74;92;72;72;73;87;71;74;91 12:25:09 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;84;88;90;87;91;87;92;105;89;87 12:25:09 INFO - PROCESS | 1651 | |6;imaging-darkroom;96;95;96;96;95;96;95;116;151;118 12:25:09 INFO - PROCESS | 1651 | |7;imaging-desaturate;88;92;85;85;98;104;78;130;140;136 12:25:09 INFO - PROCESS | 1651 | |8;json-parse-financial;57;57;58;58;60;59;59;60;58;57 12:25:09 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;78;106;70;90;105;99;99;85;73;73 12:25:09 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;74;106;101;82;88;84;89;85;82;88 12:25:09 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;142;142;141;139;141;160;174;153;196;177 12:25:09 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;168;214;222;167;165;190;203;166;167;165 12:25:09 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;66;67;69;67;70;68;68;67;68;69 12:25:09 INFO - PROCESS | 1651 | __end_tp_report 12:25:09 INFO - PROCESS | 1651 | __start_cc_report 12:25:09 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,910 12:25:09 INFO - PROCESS | 1651 | __end_cc_report 12:25:09 INFO - PROCESS | 1651 | __startTimestamp1486067109404__endTimestamp 12:25:09 INFO - PROCESS | 1651 | 12:25:09 INFO - PROCESS | 1651 | ------- Summary: start ------- 12:25:09 INFO - PROCESS | 1651 | Number of tests: 14 12:25:09 INFO - PROCESS | 1651 | 12:25:09 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:117.20 Median:119.00 stddev:4.32 (3.6%) stddev-sans-first:0.53 12:25:09 INFO - PROCESS | 1651 | Values: 105.0 118.0 119.0 119.0 118.0 118.0 119.0 119.0 118.0 119.0 12:25:09 INFO - PROCESS | 1651 | 12:25:09 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:122.30 Median:115.50 stddev:14.67 (12.7%) stddev-sans-first:12.54 12:25:09 INFO - PROCESS | 1651 | Values: 147.0 113.0 112.0 143.0 112.0 113.0 116.0 115.0 140.0 112.0 12:25:09 INFO - PROCESS | 1651 | 12:25:09 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:211.00 Median:198.50 stddev:29.51 (14.9%) stddev-sans-first:30.73 12:25:09 INFO - PROCESS | 1651 | Values: 195.0 199.0 281.0 249.0 196.0 197.0 195.0 198.0 202.0 198.0 12:25:09 INFO - PROCESS | 1651 | 12:25:09 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:73.30 Median:72.00 stddev:16.78 (23.3%) stddev-sans-first:17.56 12:25:09 INFO - PROCESS | 1651 | Values: 81.0 59.0 59.0 82.0 54.0 59.0 63.0 87.0 105.0 84.0 12:25:09 INFO - PROCESS | 1651 | 12:25:09 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:78.00 stddev:8.39 (10.8%) stddev-sans-first:8.82 12:25:09 INFO - PROCESS | 1651 | Values: 82.0 74.0 92.0 72.0 72.0 73.0 87.0 71.0 74.0 91.0 12:25:09 INFO - PROCESS | 1651 | 12:25:09 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:90.00 Median:88.50 stddev:5.75 (6.5%) stddev-sans-first:5.68 12:25:09 INFO - PROCESS | 1651 | Values: 84.0 88.0 90.0 87.0 91.0 87.0 92.0 105.0 89.0 87.0 12:25:09 INFO - PROCESS | 1651 | 12:25:09 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:105.40 Median:95.50 stddev:18.34 (19.2%) stddev-sans-first:19.14 12:25:09 INFO - PROCESS | 1651 | Values: 96.0 95.0 96.0 96.0 95.0 96.0 95.0 116.0 151.0 118.0 12:25:09 INFO - PROCESS | 1651 | 12:25:09 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:103.60 Median:85.00 stddev:23.16 (27.2%) stddev-sans-first:23.87 12:25:09 INFO - PROCESS | 1651 | Values: 88.0 92.0 85.0 85.0 98.0 104.0 78.0 130.0 140.0 136.0 12:25:09 INFO - PROCESS | 1651 | 12:25:09 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:58.50 stddev:1.16 (2.0%) stddev-sans-first:1.13 12:25:09 INFO - PROCESS | 1651 | Values: 57.0 57.0 58.0 58.0 60.0 59.0 59.0 60.0 58.0 57.0 12:25:09 INFO - PROCESS | 1651 | 12:25:09 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:87.80 Median:81.50 stddev:13.91 (17.1%) stddev-sans-first:14.30 12:25:09 INFO - PROCESS | 1651 | Values: 78.0 106.0 70.0 90.0 105.0 99.0 99.0 85.0 73.0 73.0 12:25:09 INFO - PROCESS | 1651 | 12:25:09 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:84.50 stddev:9.35 (11.1%) stddev-sans-first:8.46 12:25:09 INFO - PROCESS | 1651 | Values: 74.0 106.0 101.0 82.0 88.0 84.0 89.0 85.0 82.0 88.0 12:25:09 INFO - PROCESS | 1651 | 12:25:09 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:156.50 Median:156.50 stddev:19.77 (12.6%) stddev-sans-first:20.26 12:25:09 INFO - PROCESS | 1651 | Values: 142.0 142.0 141.0 139.0 141.0 160.0 174.0 153.0 196.0 177.0 12:25:09 INFO - PROCESS | 1651 | 12:25:09 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.70 Median:179.00 stddev:22.62 (12.6%) stddev-sans-first:23.36 12:25:09 INFO - PROCESS | 1651 | Values: 168.0 214.0 222.0 167.0 165.0 190.0 203.0 166.0 167.0 165.0 12:25:09 INFO - PROCESS | 1651 | 12:25:09 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:1.20 (1.8%) stddev-sans-first:1.05 12:25:09 INFO - PROCESS | 1651 | Values: 66.0 67.0 69.0 67.0 70.0 68.0 68.0 67.0 68.0 69.0 12:25:09 INFO - PROCESS | 1651 | -------- Summary: end -------- 12:25:09 INFO - PROCESS | 1651 | 12:25:10 INFO - TEST-INFO | 1651: exit 0 12:25:10 INFO - TEST-OK | kraken | took 60502ms 12:25:10 INFO - SUITE-END | took 1175s 12:25:10 INFO - Completed test suite (00:19:35) 12:25:10 INFO - javascript benchmark 12:25:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7286.0972286595925, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3532.4524698671707, 3545.3618348697223, 3560.4837298591706, 3576.4098923677593, 3593.4969104325382, 4325.178216138931, 5828.708564571775, 5875.823725288237, 5903.321118071418, 5926.9407305926925, 5875.647461152334, 5931.911021334683, 5952.761889524414, 5961.940380596193, 5968.2838059432825, 5327.147656374981, 5432.049391356497, 5435.130379139338, 5483.588730845183, 5492.588055895804, 5471.50756431921, 5484.890302193954, 5488.725563721818, 5489.972550137264, 5502.862428439281, 5325.7869685212545, 5369.087255166612, 5478.342598888119, 5484.533814625762, 5520.723963801814, 5358.65168764031, 5373.462653734634, 5430.972845135789, 5458.91811622822, 5508.807191748269, 5323.121684921984, 5396.460353964611, 5411.458854114597, 5412.107002344608, 5431.945680543184, 5718.285214348207, 5918.940810591925, 5954.821355359346, 5996.760129594811, 6006.399360064002, 6184.96907515464, 6198.194234749454, 6208.255009398856, 6251.4061164189325, 6300.684965751694, 6250.874982500324, 6276.686165691697, 6290.056491526308, 6290.591111577733, 6307.0539419087045, 5938.435848594377, 5944.197533333031, 5966.880662386728, 5975.312839023524, 5988.37122102173, 6206.379362063848, 6208.286047104551, 6295.307516173218, 6315.873682526324, 6354.0, 6242.595416031357, 6298.590591611623, 6306.369363063749, 6320.115183874262, 6326.715297811609, 6307.400796924377, 6312.621242725451, 6319.652419116901, 6326.557141000086, 6330.177076979963, 4562.840300589463, 6146.692665366714, 6208.782692605737, 6225.28409232935, 6237.532185086136, 5229.6339256251695, 6235.345288744707, 6281.371862813773, 6285.874282514415, 6288.276848162428, 5061.5950723942, 5066.16408292635, 5068.847934561969, 5076.035553244872, 5088.109580823341, 3806.352920003606, 4409.294512877924, 4469.932951005738, 4475.1049790042125, 4548.977255113703, 5945.2865656120675, 6064.272287325549, 6086.421789929949, 6111.5309653553995, 6112.480439162632, 5184.7926082956255, 6304.432601065926, 6312.989921612611, 6323.019931910561, 6326.557141000086, 5950.434708702666, 6039.184710064085, 6042.546808989343, 6098.237720284965, 6110.114033465123, 5581.162825576196, 5589.888202236014, 5611.2144299798065, 5615.045442274742, 5618.9604923089355, 6562.50781191403, 6592.934070659232, 6602.1747281589805, 6621.86756264872, 6628.734850605922, 5527.861803454945, 6028.939710602838, 6147.262328520606, 6233.719482623203, 6304.7162877671535, 6485.967570162119, 6600.702968366531, 6614.66926653675, 6630.701618427279, 6632.801015969336, 6243.500519958483, 6244.250689917239, 6248.0003199487865, 6253.437190652863, 6267.435930766253, 6114.723190878713, 6211.2857021441305, 6299.496040316855, 6328.715207815567, 6351.428371446592, 6077.058056001327, 6144.539159562962, 6230.470410015199, 6245.8750824982335, 6292.276388215414, 6239.469645080219, 6260.123582698517, 6284.4344009039405, 6298.401651843067, 6307.936920630735, 5126.282320475062, 6008.128821320972, 6040.728167232573, 6172.444479996821, 6213.099100630293, 6143.72682107623, 6172.876542469216, 6178.0, 6229.0, 6301.936980630318, 6155.1075094112, 6158.969205154125, 6165.10605962124, 6168.444839964424, 6172.814815555541], "value": 5817.012326062291, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.935268415106, 508.87532554524137, 520.1443625236484, 542.4439949052221, 555.466751918159, 39392.0, 41101.97245068871, 41165.38251926223, 41237.79381103094, 41242.96892577687, 42203.155936881245, 42548.57451425485, 42683.14633707324, 42764.14471710564, 42937.35593966093, 9574.28192885533, 9763.609455621767, 9764.365316254421, 9771.071748183911, 9775.217982561378, 9765.365251258681, 9809.411435313905, 9811.411315321067, 9845.310828242025, 9873.16078133356, 9731.318807683465, 9809.754756131118, 9842.0, 9862.753431164207, 9893.752656183617, 9755.853662195073, 9764.218862490983, 9825.213982881352, 9836.90163098367, 9876.111149996463, 9409.435433873921, 9460.90539094614, 9544.665936692252, 9563.808723825485, 9580.233581313549, 40798.79600602008, 41006.38490422646, 41052.589474105174, 41155.0, 41163.97090072772, 3380.4591265397416, 3387.5088112223834, 3388.254583991518, 3582.6417358264225, 3586.7847929124337, 28874.422511549652, 30036.24909377283, 30289.0, 31902.840485797653, 32144.03567892967, 3090.7945901098587, 3298.422776014188, 3369.0566641340465, 3372.7301815854917, 3484.0593039879345, 414005.9299703512, 497773.0, 498147.0, 501999.4900025476, 506011.0, 45849.0, 58858.0, 58883.99999999914, 59101.0, 60362.69818650967, 58303.8339233213, 59863.0, 60859.69570152121, 60981.390186098455, 61050.38949610447, 14217.5782421757, 16382.508524744277, 16393.934394264335, 16538.58653533647, 16670.0, 12854.971602271797, 12964.675883102998, 12983.935080324538, 12996.48014079445, 13178.143420677625, 15248.923755381153, 15475.226238688021, 15800.130992795277, 15816.60458488547, 15835.920820395824, 6093.908591371133, 6121.816345509642, 6182.443580077815, 6226.968865155736, 6255.530835187379, 857.9227869491775, 1003.6035765872585, 1010.1767059846202, 1012.3116280928891, 1064.6007747094839, 27156.86421567919, 30240.69759302379, 30337.0, 30946.381072378426, 31045.379092418025, 987.4766373821886, 1106.336198281041, 1127.9548818047351, 1201.1351826684772, 1227.0551675210218, 13939.860601394059, 14314.0, 16653.0, 16717.414890478776, 16837.0, 688814.0, 694031.0, 694256.0, 696023.0, 696850.0, 58214.0, 58258.708706457044, 58554.414455855745, 58631.41368586259, 58784.82430351369, 1390.242317936727, 1396.7765157574738, 1414.1515090945556, 1416.1290806154348, 1416.6883285677134, 30662.386752265276, 30896.536551952187, 31549.89575364851, 32127.67872321247, 32329.19177020499, 55207.44792552023, 56908.0, 57027.0, 59188.0, 59917.700411497666, 628525.0, 629962.0, 636425.0, 636874.0, 637878.0, 26783.0, 42434.0, 42571.57428425676, 43031.569684302754, 43037.56962430461, 43979.34030989602, 44884.77557612191, 45326.77336613428, 45966.0, 46214.53785462103, 37641.87074387773, 37773.24453510969, 38798.2240355197, 39898.601013989486, 39949.0, 38207.617923820406, 38421.6157838418, 39252.607473924894, 39285.41071883867, 39688.206235875696, 37432.81283593673, 38305.6169438302, 38543.42184867285, 39110.6088939107, 39917.600823991386, 32791.0, 34094.82952585221, 34236.657633424344, 34741.305173896886, 34839.65160348364, 24656.39234288317, 25668.87165564235, 25859.741402586485, 25919.352016199748, 26213.95144194287], "value": 20324.463402892085, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.3556552594936, 359.08791669160337, 372.9160938788772, 387.55237700455984, 410.64896490546107, 15875.761863572041, 19241.711374329378, 20434.89782551089, 20629.793702062994, 20887.582248355026, 10875.293105948127, 11784.116191285644, 19263.807361926378, 19885.602287954232, 19929.0, 5370.489803468664, 5599.41206173352, 5606.187102870082, 5612.382637909847, 5627.634203776762, 4575.931361029588, 4587.63298936086, 5465.289512363387, 6867.793966181022, 6934.514583979124, 6648.202215734119, 6808.353206445405, 6842.55523390978, 6872.450203983669, 6951.513394062417, 6699.263081061079, 6828.487863410214, 6831.2144103428245, 6831.726730930757, 6831.829204269883, 5299.54953828925, 5579.69311687857, 5635.6055076144685, 5989.251343582052, 6071.939280607182, 13136.752008559171, 13173.736525269536, 13364.198148111047, 13400.93299533496, 13602.727945441135, 2315.7336906255828, 2331.463763334443, 2427.9393015174587, 2865.1834227245345, 2961.2300801791475, 9869.259805514615, 10021.649242276486, 11005.0, 15357.539273821803, 15747.055176689435, 1845.2065611786927, 1896.781870084944, 1924.7177411353805, 1970.714246434259, 1981.2075169931998, 115636.42181789121, 121038.18442723366, 127494.72505274921, 128469.0, 130001.69998299991, 11644.53421863116, 11891.28652280866, 11941.402929853646, 11943.761124777457, 11970.640880773417, 14937.730292925005, 14943.925280373529, 15030.32363543643, 15074.246287685572, 15101.697966040838, 8602.741917742353, 8752.781180470412, 8753.0, 8796.560171991376, 8826.338024648177, 7177.605231712201, 7469.663865126083, 8750.124987501325, 8759.868601970975, 8768.693095741619, 4586.495485496593, 4620.3993480847275, 4641.2109941310055, 4699.976500117477, 4703.64722645803, 1763.515033365809, 1948.4252145617077, 2008.9296874609317, 2021.2016253579754, 2047.5495391013953, 791.6239786101555, 882.8719835623799, 914.711865762296, 967.4969016111583, 1032.8554002439664, 19703.40889773309, 20655.69016464754, 20672.276470323766, 20744.0, 20893.0, 929.2101713543434, 948.1321796061748, 967.8257913575483, 1012.7822518158631, 1016.7356487313203, 10403.595514605431, 10960.506777195045, 10966.835497467544, 10988.0, 11059.281146725598, 602380.0, 609630.0, 615974.0, 627230.0, 629284.0, 91130.54434727784, 92579.53710231406, 107023.46488267665, 107994.46002769936, 109568.0, 975.6341371985505, 1005.4269066632096, 1009.4902074452355, 1025.5282570017725, 1028.665683652801, 17552.82447175563, 17995.910020449814, 18346.990915499777, 18375.805572637735, 18679.066046697884, 51857.96284074373, 52808.0, 53129.0, 53207.0, 53400.73299633477, 72097.0, 73916.89124663027, 74785.6260718693, 75961.0, 79294.0, 5216.686998780086, 7171.175314838861, 7480.925190748023, 7572.318491335806, 7848.136704962335, 10635.787284254426, 12293.815592766294, 12873.35633218354, 12908.096433249582, 12960.0, 5388.164834450667, 8379.743038544024, 9326.487043212688, 10380.792384152426, 10665.840012399975, 6332.968335158294, 9877.802443950937, 10688.076146293432, 10810.729731756768, 11513.021393181674, 11122.61070862516, 11141.0, 11486.36824974601, 11642.359670218219, 11698.883011169779, 7785.260400261966, 8242.587870606565, 8289.917100828914, 8325.333973282244, 8348.916510834813, 8145.551994640352, 10233.181345492194, 10735.087517561095, 10758.193135514712, 11535.134864885], "value": 9321.472522699545, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.7026059657771, 1502.6619010722586, 1716.3821024431202, 1843.5022543913153, 1884.6984482482796, 6509.544331896769, 7783.727569535067, 8282.958585207072, 8726.52004139772, 8734.0, 8271.83456330873, 8543.27382172516, 8550.70072547461, 8637.956810215956, 8646.654133834654, 2218.7781221877844, 2271.738750043741, 2389.892454839532, 2421.443068094339, 2429.2469334506272, 2414.9034038638433, 2437.2322718343667, 2440.694913135858, 2455.607102863542, 2457.8033757299463, 2030.7055476955832, 2375.489269806991, 2397.9640305395346, 2402.1112188490197, 2420.177139772477, 2374.833761636686, 2383.2135395319588, 2390.4501964548253, 2397.6043952747864, 2403.42317843718, 2409.421738782697, 2422.454947636785, 2436.9634455483183, 2444.4255599934, 2444.706635203769, 7410.740624078131, 8566.871496927552, 8643.43817651857, 8675.739727808175, 8677.869831952463, 5256.789728410859, 5393.325834270716, 5413.702246376448, 5423.837284881421, 5430.0768869292315, 3347.8828241011447, 3348.79907205566, 3504.4743288506675, 3594.388953877847, 3656.360136976045, 1036.642358386363, 1080.7730376620918, 1086.8532748078987, 1126.7239526316105, 1143.6168883424064, 11232.831507527393, 11237.213395062348, 11251.381174035425, 11255.099592032619, 11286.774264514665, 4586.2661974083985, 4736.95263047372, 4802.495737947535, 4818.963922756624, 4880.0, 9650.662226822027, 9653.17947974416, 9667.274954378308, 9677.080677335642, 9733.9026609733, 5320.361556613231, 5356.250124982505, 5365.034293827149, 5365.731713414314, 5384.461553844662, 4444.711093778894, 4485.349624304457, 4875.512448755097, 4923.310736496894, 4967.056259310792, 3921.745086569364, 3966.900827479336, 4016.397540368968, 4056.1887622475624, 4065.4308396824467, 1307.0, 1310.462710288777, 1312.8949684025256, 1318.4726109556063, 1325.337331334333, 807.3904202327205, 886.9822603547893, 902.5848109869534, 912.3613470570627, 918.2975024106564, 5660.2075709400715, 5697.715114244271, 5716.799912003143, 5719.771209151588, 5798.623089499253, 950.8003319303024, 960.7261930349816, 962.2013728605241, 962.4562122400821, 1021.8058568871891, 3636.309101270803, 3698.0939669780537, 3718.3121122592406, 3765.284595926765, 3809.1239015026144, 10125.696229112991, 10471.633492827714, 10471.738206544745, 10482.21383396233, 10499.160067194605, 8812.735617931472, 10223.642172523925, 10428.5828566858, 10446.68659940203, 10449.425281609585, 7730.458867879307, 8042.879356809653, 8050.396220283385, 8225.506469611842, 8233.711820086268, 5410.161424979135, 5449.700266485382, 5630.718464076862, 5694.430556944272, 5706.771729130788, 7870.212978702084, 9432.245420366216, 9590.08894155054, 9686.02810113211, 9692.70921872345, 9633.229341652792, 9683.983181766094, 9839.606415743576, 9941.005899410002, 9959.800803983735, 6315.747370105144, 6330.651814150082, 6952.0, 7105.3960413363375, 7156.677949492181, 8023.879641805261, 8063.959680201796, 8074.313683336982, 8140.674373025013, 8141.511509309224, 7153.6423178839805, 7180.640967951686, 7339.155997060407, 7367.742129025459, 7413.592252426169, 7088.716451341888, 7266.273372662691, 7274.272572742683, 7300.780976570712, 7317.8902316466365, 5694.0, 6637.137172167587, 6644.800655980328, 6645.9003114954285, 6665.566738162004, 4637.956459796519, 4674.462436819809, 4713.67004309695, 4744.525547445228, 4751.311059896365, 4423.960369313145, 4771.0, 4879.243717223836, 5025.824096156617, 5071.0], "value": 4510.251686712434, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.24203281631833, 308.80236648544934, 309.9101260634416, 316.9857356418961, 321.63012535584073, 5409.323834520685, 5505.284313039304, 5516.889662206753, 5564.944350556493, 5601.0, 4503.77481125944, 5224.63427560071, 5315.468453154683, 5408.513233808956, 5500.717202361026, 3530.5939816920995, 3711.480392745018, 3830.6743926766258, 3958.3864501002245, 4211.599898009684, 3353.899383018513, 3797.506324177853, 4210.915781684364, 4223.61987421132, 4227.767472788995, 4106.568810274923, 4140.337545992642, 4147.854825081123, 4179.561146079664, 4198.992241861946, 3667.8812962046563, 4129.009037830929, 4130.586941305861, 4150.605692459211, 4156.210320039183, 3300.5379246905454, 3376.902506685337, 3503.754737168399, 3550.414181660023, 3559.2238054317672, 5308.283381743454, 5315.867103322409, 5317.335333083364, 5367.570594352443, 5374.97875403676, 4614.884627884297, 4718.339432479455, 4720.173969555314, 4728.912350159482, 4763.785629646674, 4995.500449955011, 5408.540274076709, 5669.773209071632, 5672.631278966854, 5702.486776190121, 3903.1608204236227, 3929.9017524561827, 3935.783531905744, 3947.4670919426085, 3951.4270430787374, 5283.577313814885, 5943.3745415296635, 6039.939600604026, 6056.21269235001, 6100.389961003864, 3661.7802931823644, 3697.7226707997006, 3940.9014774630864, 3970.828605561366, 4035.8183881725395, 3407.216340241758, 3793.962060379416, 3813.5995720448955, 4007.318755811519, 4033.5361433434946, 3533.8338348344982, 4451.772464962876, 4675.929861052087, 4695.319178719066, 4715.929261061086, 4841.95158048422, 5281.445448227958, 5566.248556444909, 5654.491095801398, 5819.719661674425, 1199.0767109325946, 1230.6308107567693, 1291.4188615123232, 1320.4124164746788, 1333.973320533584, 797.365400928095, 824.8864033554653, 840.8234270803077, 869.9391042626955, 900.4957223954607, 4163.687723420756, 4488.977555112268, 4521.965115697883, 5188.195829646411, 5286.418493965661, 5025.246213068069, 5106.744662766847, 5595.972020139873, 5635.6618602883955, 5657.066584013674, 4425.026494171278, 5126.820561280338, 5147.613928955268, 5179.452075328337, 5261.0, 2938.206684195255, 2998.250437390652, 3026.1856473479875, 3038.95156171125, 3051.908442746721, 5245.947540524622, 5405.891882162414, 6018.21763170785, 6060.575759696779, 6074.51403887688, 5171.3535808024, 5267.0256002639635, 5333.786648534093, 5481.5296284259375, 5594.104943209067, 5789.623674461147, 5880.500157486576, 5996.250468691326, 6055.545834062463, 6072.271327440735, 4620.930686039779, 4857.757112144308, 5322.38792538863, 5353.839384818461, 5669.659820410789, 4942.1598328285, 5270.130428479258, 5442.9930462864495, 5596.888062238651, 5688.601797874275, 5069.493050694901, 5935.643861368331, 5950.910736339045, 5965.701714914324, 5969.701514924323, 3283.31050479402, 3749.531308586427, 3780.6597406233573, 3787.5833658297015, 3826.9617303826603, 5002.899942001212, 5178.870528236674, 5919.852003699942, 5948.851278718067, 6030.703398769286, 5481.260029895913, 5608.0, 5690.691141037667, 5709.885802284014, 5792.507636850746, 4870.95129048705, 5531.3362396512675, 5752.28096487939, 5752.798652047326, 5759.617691754032, 4582.289745089516, 4800.375951126471, 5179.4779549906425, 5651.5415794129685, 5677.971610141923, 5293.788248470172, 5777.422257774357, 5778.739956701875, 5821.70891455417, 5838.970805145947, 5628.577856660685, 5662.915056274077, 5672.893785711746, 5686.317641883001, 5697.800576979786], "value": 4104.956330117632, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [627.3945642455031, 742.5322047110321, 755.66372964031, 761.3490465651864, 786.3080489169533, 14361.35373908174, 15045.699086018287, 15080.924595377035, 15116.39534418622, 15193.848061519382, 13881.930590347034, 14054.64863378416, 14152.575422737309, 14200.644983875409, 14229.501967431142, 680.9421199198061, 847.9152084791533, 850.3409857360564, 869.4652788535037, 878.1306506558525, 854.5599016506504, 860.2601762484259, 861.3066481482405, 861.4658911474894, 864.6714248585529, 923.224491427201, 927.2211342472322, 927.717046300878, 929.5305870535404, 932.7994481186544, 760.7413479417024, 900.2168113741054, 907.773056735816, 917.5091326140483, 918.9540522973858, 679.4835924697217, 681.417388133149, 681.9736296873224, 682.1234713393266, 683.4429939599261, 12449.502019919193, 12484.375781210903, 12562.623121306284, 12606.180598261175, 12656.74686506274, 13965.3715582798, 14024.0, 14043.297835108204, 14085.225312607803, 14101.78847317291, 11106.888931110667, 11527.423628818526, 11548.826767598493, 11701.414929253588, 12214.083943704258, 3622.275544891006, 3721.9627803721887, 3798.5441746990264, 3813.48517950076, 3855.094052897567, 77545.22454775493, 83237.58381208115, 90132.0, 93959.12081758327, 94064.52967735186, 9682.0, 10872.836907446395, 11158.330500170016, 11183.72040698989, 11335.149863760085, 20721.067551959895, 20746.58506829855, 21002.0, 21189.78810211909, 21204.575908481744, 8022.438429310009, 8041.7989550260545, 8083.636236369378, 8105.27052565272, 8140.552269625109, 4535.478419981679, 4575.153596584642, 4576.702514336557, 4584.679072464895, 4587.8623641290815, 3441.6042155152477, 3550.55618047744, 3555.253396786652, 3739.476473293741, 3909.3940439231965, 631.703099543214, 644.1336402538578, 712.8966299886488, 720.7873677265219, 724.6376811594203, 601.7743346245159, 602.665096810558, 657.4707360574688, 662.9624637442387, 667.9532432729662, 9079.364444488954, 9267.536623168815, 9302.813733819672, 9482.668106616235, 9737.618071867688, 635.4304866978556, 658.3153520338794, 659.581165959619, 662.5262937000068, 675.9121314229119, 3539.7876127432437, 3827.6937844972335, 4104.610062044101, 4115.629593336614, 4681.227597446451, 58492.0, 75079.62460187664, 75563.0, 112775.0, 126858.0, 65430.0, 67844.6607766958, 84430.57784711037, 85005.14994850096, 87605.0, 63066.68466657729, 64832.0, 64947.02579461312, 70631.94052089223, 80393.0, 9131.726048218565, 9365.250779937453, 9913.432835820895, 9923.206143508358, 10081.848772268275, 40096.59903400928, 52995.73502132465, 55322.17016744833, 57576.0, 58650.70674646599, 56394.71802641125, 65078.674606626664, 77467.61266193633, 78249.82625260511, 81570.73717051488, 12950.287734174712, 13167.60497185086, 13220.2152709383, 13391.598252052454, 13511.324433778074, 14754.0, 14813.185274809988, 15078.0199287046, 15079.924600377366, 15250.0, 12032.571901531292, 12058.276503409823, 12058.577949771716, 12168.57409990646, 12317.44571494267, 13303.0, 13495.0, 13757.96815238881, 13828.239446830119, 14199.929000355345, 13196.60410187696, 13348.199108053548, 13710.0402971791, 13723.725525489235, 14435.855641443452, 9746.658866939622, 9839.5080245986, 11210.327380357205, 11538.94230528842, 11550.59572914944, 11454.427278635869, 11468.770624587629, 11551.711207219887, 11619.244749091282, 11754.177207595387], "value": 7331.7992698323815, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1542.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 119.0, 119.0, 118.0, 118.0, 119.0, 119.0, 118.0, 119.0], "value": 117.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 113.0, 112.0, 143.0, 112.0, 113.0, 116.0, 115.0, 140.0, 112.0], "value": 122.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 199.0, 281.0, 249.0, 196.0, 197.0, 195.0, 198.0, 202.0, 198.0], "value": 211.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 82.0, 54.0, 59.0, 63.0, 87.0, 105.0, 84.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 74.0, 92.0, 72.0, 72.0, 73.0, 87.0, 71.0, 74.0, 91.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 88.0, 90.0, 87.0, 91.0, 87.0, 92.0, 105.0, 89.0, 87.0], "value": 90.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 116.0, 151.0, 118.0], "value": 105.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 92.0, 85.0, 85.0, 98.0, 104.0, 78.0, 130.0, 140.0, 136.0], "value": 103.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 58.0, 58.0, 60.0, 59.0, 59.0, 60.0, 58.0, 57.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 106.0, 70.0, 90.0, 105.0, 99.0, 99.0, 85.0, 73.0, 73.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 106.0, 101.0, 82.0, 88.0, 84.0, 89.0, 85.0, 82.0, 88.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 142.0, 141.0, 139.0, 141.0, 160.0, 174.0, 153.0, 196.0, 177.0], "value": 156.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 214.0, 222.0, 167.0, 165.0, 190.0, 203.0, 166.0, 167.0, 165.0], "value": 182.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 69.0, 67.0, 70.0, 68.0, 68.0, 67.0, 68.0, 69.0], "value": 67.9, "unit": "score"}]}]} 12:25:10 INFO - Return code: 0 12:25:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:25:10 ERROR - # TBPL SUCCESS # 12:25:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:25:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:25:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:25:10 INFO - 2017-02-02 12:25:10.749 screenresolution[1656:10472] starting screenresolution argv=screenresolution get 12:25:10 INFO - 2017-02-02 12:25:10.754 screenresolution[1656:10472] Display 0: 1600x1200x32@60 12:25:10 INFO - 2017-02-02 12:25:10.760 screenresolution[1657:10475] starting screenresolution argv=screenresolution list 12:25:10 INFO - Available Modes on Display 0 12:25:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:25:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:25:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:25:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:25:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:25:10 INFO - Intel Iris: 12:25:10 INFO - Chipset Model: Intel Iris 12:25:10 INFO - Type: GPU 12:25:10 INFO - Bus: Built-In 12:25:10 INFO - VRAM (Dynamic, Max): 1536 MB 12:25:10 INFO - Vendor: Intel (0x8086) 12:25:10 INFO - Device ID: 0x0a2e 12:25:10 INFO - Revision ID: 0x0009 12:25:10 INFO - Displays: 12:25:10 INFO - Display: 12:25:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:25:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:25:10 INFO - Display Serial Number: 1600x1200 60 12:25:10 INFO - Main Display: Yes 12:25:10 INFO - Mirror: Off 12:25:10 INFO - Online: Yes 12:25:10 INFO - Rotation: Supported 12:25:10 INFO - Return code: 0 12:25:10 INFO - Running post-action listener: _resource_record_post_action 12:25:10 INFO - [mozharness: 2017-02-02 20:25:10.999085Z] Finished run-tests step (success) 12:25:10 INFO - Running post-run listener: _resource_record_post_run 12:25:11 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 40376320; Write bytes: 502103552; Read time: 393; Write time: 1650 12:25:11 INFO - TinderboxPrint: CPU usage
54.4% 12:25:11 INFO - TinderboxPrint: I/O read bytes / time
40,376,320 / 393 12:25:11 INFO - TinderboxPrint: I/O write bytes / time
502,103,552 / 1,650 12:25:11 INFO - TinderboxPrint: CPU idle
2,183.3 (45.6%) 12:25:11 INFO - TinderboxPrint: CPU system
967.3 (20.2%) 12:25:11 INFO - TinderboxPrint: CPU user
1,639.1 (34.2%) 12:25:11 INFO - TinderboxPrint: Swap in / out
232,206,336 / 0 12:25:11 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 183251456; Write bytes: 175550464; Read time: 16768; Write time: 313 12:25:11 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 38553600; Write bytes: 309005824; Read time: 374; Write time: 1286 12:25:11 INFO - Running post-run listener: _upload_blobber_files 12:25:11 INFO - Blob upload gear active. 12:25:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:25:11 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 12:25:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:25:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:25:11 INFO - (blobuploader) - INFO - Open directory for files ... 12:25:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:25:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:25:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:25:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:25:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:25:13 INFO - (blobuploader) - INFO - Done attempting. 12:25:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:25:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:25:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:25:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:25:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:25:14 INFO - (blobuploader) - INFO - Done attempting. 12:25:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:25:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:25:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:25:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:25:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:25:16 INFO - (blobuploader) - INFO - Done attempting. 12:25:16 INFO - (blobuploader) - INFO - Iteration through files over. 12:25:16 INFO - Return code: 0 12:25:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:25:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:25:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/631906e3661bbd3a03414626251688013882263d2ebaa568937e1b3e68244c11a660aaf56e4bf44ff487944b3767306a5494920a45d18d3048d80fe4cbc39718", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5762b3ecadae483c3ee35cf2d74ee96a152c23824276970f70a1374e2b394e5cab614871a9adc38fa639d2c9ca5cf5de520fe3b0dbdedfc47102215c68ad74b5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e18033d2649f0123623ed00d325c00ecc61f10b69322783b8227a5fbee54ecb23e691cae8d143f4b5b83be7a14b5bd43ce85456474156c98d7f84f2a62336817"} 12:25:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:25:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:25:16 INFO - Contents: 12:25:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/631906e3661bbd3a03414626251688013882263d2ebaa568937e1b3e68244c11a660aaf56e4bf44ff487944b3767306a5494920a45d18d3048d80fe4cbc39718", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5762b3ecadae483c3ee35cf2d74ee96a152c23824276970f70a1374e2b394e5cab614871a9adc38fa639d2c9ca5cf5de520fe3b0dbdedfc47102215c68ad74b5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e18033d2649f0123623ed00d325c00ecc61f10b69322783b8227a5fbee54ecb23e691cae8d143f4b5b83be7a14b5bd43ce85456474156c98d7f84f2a62336817"} 12:25:16 INFO - Running post-run listener: copy_logs_to_upload_dir 12:25:16 INFO - Copying logs to upload dir... 12:25:16 INFO - mkdir: /builds/slave/test/build/upload/logs 12:25:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.209931 ========= master_lag: 4.01 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-02 12:25:20.409262) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-02-02 12:25:20.413662) ========= 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.0PfSq3bjQz/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.BP0r6IFkAX/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/631906e3661bbd3a03414626251688013882263d2ebaa568937e1b3e68244c11a660aaf56e4bf44ff487944b3767306a5494920a45d18d3048d80fe4cbc39718", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5762b3ecadae483c3ee35cf2d74ee96a152c23824276970f70a1374e2b394e5cab614871a9adc38fa639d2c9ca5cf5de520fe3b0dbdedfc47102215c68ad74b5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e18033d2649f0123623ed00d325c00ecc61f10b69322783b8227a5fbee54ecb23e691cae8d143f4b5b83be7a14b5bd43ce85456474156c98d7f84f2a62336817"} build_url:https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010061 build_url: 'https://queue.taskcluster.net/v1/task/Yq3o3v1UTE6rkR0igaKVUw/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/631906e3661bbd3a03414626251688013882263d2ebaa568937e1b3e68244c11a660aaf56e4bf44ff487944b3767306a5494920a45d18d3048d80fe4cbc39718", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5762b3ecadae483c3ee35cf2d74ee96a152c23824276970f70a1374e2b394e5cab614871a9adc38fa639d2c9ca5cf5de520fe3b0dbdedfc47102215c68ad74b5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e18033d2649f0123623ed00d325c00ecc61f10b69322783b8227a5fbee54ecb23e691cae8d143f4b5b83be7a14b5bd43ce85456474156c98d7f84f2a62336817"}' ========= master_lag: 1.95 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-02-02 12:25:22.377340) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 12:25:22.377685) ========= 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.0PfSq3bjQz/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.BP0r6IFkAX/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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.005404 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 12:25:22.428060) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 12:25:22.428423) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 12:25:22.428962) ========= ========= Total master_lag: 6.08 =========