builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0165 starttime: 1484684124.41 results: success (0) buildid: 20170117065523 builduid: bf0c60270e6f48c9b1c16a87d9a7a92c revision: 2356029b0bb5085b468d0c6a1acc58e8c664fb12 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-17 12:15:24.408762) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-17 12:15:24.409183) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-17 12:15:24.409475) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-17 12:15:24.437590) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 12:15:24.437955) ========= 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.TfVsuxYWqJ/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.CN1NYwHi9d/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-17 12:15:24-- 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.7M=0.001s 2017-01-17 12:15:24 (14.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.177608 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 12:15:24.639920) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 12:15:24.640235) ========= 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.TfVsuxYWqJ/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.CN1NYwHi9d/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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.026884 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 12:15:24.690328) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-17 12:15:24.690702) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2356029b0bb5085b468d0c6a1acc58e8c664fb12 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2356029b0bb5085b468d0c6a1acc58e8c664fb12 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TfVsuxYWqJ/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.CN1NYwHi9d/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-17 12:15:24,786 truncating revision to first 12 chars 2017-01-17 12:15:24,786 Setting DEBUG logging. 2017-01-17 12:15:24,786 attempt 1/10 2017-01-17 12:15:24,786 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2356029b0bb5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-17 12:15:26,500 unpacking tar archive at: mozilla-aurora-2356029b0bb5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.991780 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-17 12:15:26.710556) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-17 12:15:26.710899) ========= script_repo_revision: 2356029b0bb5085b468d0c6a1acc58e8c664fb12 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-17 12:15:26.711280) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-17 12:15:26.711564) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-17 12:15:26.731837) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-17 12:15:26.732184) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TfVsuxYWqJ/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.CN1NYwHi9d/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:15:26 INFO - MultiFileLogger online at 20170117 12:15:26 in /builds/slave/test 12:15:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:15:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:15:26 INFO - {'append_to_log': False, 12:15:26 INFO - 'base_work_dir': '/builds/slave/test', 12:15:26 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:15:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:15:26 INFO - 'branch': 'Mozilla-Aurora', 12:15:26 INFO - 'buildbot_json_path': 'buildprops.json', 12:15:26 INFO - 'config_files': ('talos/mac_config.py',), 12:15:26 INFO - 'default_actions': ('clobber', 12:15:26 INFO - 'read-buildbot-config', 12:15:26 INFO - 'download-and-extract', 12:15:26 INFO - 'populate-webroot', 12:15:26 INFO - 'create-virtualenv', 12:15:26 INFO - 'install', 12:15:26 INFO - 'run-tests'), 12:15:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:15:26 INFO - 'download_minidump_stackwalk': True, 12:15:26 INFO - 'download_symbols': 'ondemand', 12:15:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:15:26 INFO - 'tooltool.py': '/tools/tooltool.py', 12:15:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:15:26 INFO - '/tools/misc-python/virtualenv.py')}, 12:15:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:15:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:15:26 INFO - 'installer_path': 'installer.exe', 12:15:26 INFO - 'log_level': 'info', 12:15:26 INFO - 'log_name': 'talos', 12:15:26 INFO - 'log_to_console': True, 12:15:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:15:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:15:26 INFO - 'opt_config_files': (), 12:15:26 INFO - 'pip_index': False, 12:15:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:15:26 INFO - 'cmd': ('bash', 12:15:26 INFO - '-c', 12:15:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:15:26 INFO - 'enabled': True, 12:15:26 INFO - 'halt_on_failure': False, 12:15:26 INFO - 'name': 'check_screen_resolution'},), 12:15:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:15:26 INFO - 'cmd': ('bash', 12:15:26 INFO - '-c', 12:15:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:15:26 INFO - 'enabled': True, 12:15:26 INFO - 'halt_on_failure': False, 12:15:26 INFO - 'name': 'check_screen_resolution'},), 12:15:26 INFO - 'run_cmd_checks_enabled': True, 12:15:26 INFO - 'sps_profile': False, 12:15:26 INFO - 'sps_profile_interval': 0, 12:15:26 INFO - 'suite': 'dromaeojs-e10s', 12:15:26 INFO - 'system_bits': '32', 12:15:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:15:26 INFO - 'title': 't-yosemite-r7-0165', 12:15:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:15:26 INFO - 'use_talos_json': True, 12:15:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:15:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:15:26 INFO - 'work_dir': 'build'} 12:15:26 INFO - [mozharness: 2017-01-17 20:15:26.942146Z] Running clobber step. 12:15:26 INFO - Running pre-action listener: _resource_record_pre_action 12:15:26 INFO - Running main action method: clobber 12:15:26 INFO - rmtree: /builds/slave/test/build 12:15:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:15:29 INFO - Running post-action listener: _resource_record_post_action 12:15:29 INFO - [mozharness: 2017-01-17 20:15:29.341963Z] Finished clobber step (success) 12:15:29 INFO - [mozharness: 2017-01-17 20:15:29.342097Z] Running read-buildbot-config step. 12:15:29 INFO - Running pre-action listener: _resource_record_pre_action 12:15:29 INFO - Running main action method: read_buildbot_config 12:15:29 INFO - Using buildbot properties: 12:15:29 INFO - { 12:15:29 INFO - "project": "", 12:15:29 INFO - "product": "firefox", 12:15:29 INFO - "script_repo_revision": "production", 12:15:29 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 12:15:29 INFO - "repository": "", 12:15:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 12:15:29 INFO - "buildid": "20170117065523", 12:15:29 INFO - "pgo_build": "False", 12:15:29 INFO - "basedir": "/builds/slave/test", 12:15:29 INFO - "buildnumber": 30, 12:15:29 INFO - "slavename": "t-yosemite-r7-0165", 12:15:29 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 12:15:29 INFO - "platform": "yosemite_r7", 12:15:29 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:15:29 INFO - "branch": "mozilla-aurora", 12:15:29 INFO - "repo_path": "releases/mozilla-aurora", 12:15:29 INFO - "revision": "2356029b0bb5085b468d0c6a1acc58e8c664fb12", 12:15:29 INFO - "stage_platform": "macosx64", 12:15:29 INFO - "builduid": "bf0c60270e6f48c9b1c16a87d9a7a92c", 12:15:29 INFO - "slavebuilddir": "test" 12:15:29 INFO - } 12:15:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 12:15:29 INFO - Running post-action listener: _resource_record_post_action 12:15:29 INFO - [mozharness: 2017-01-17 20:15:29.345364Z] Finished read-buildbot-config step (success) 12:15:29 INFO - [mozharness: 2017-01-17 20:15:29.345483Z] Running download-and-extract step. 12:15:29 INFO - Running pre-action listener: _resource_record_pre_action 12:15:29 INFO - Running main action method: download_and_extract 12:15:29 INFO - mkdir: /builds/slave/test/build/tests 12:15:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:15:29 INFO - trying https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 12:15:29 INFO - Downloading https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 12:15:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 12:15:30 INFO - Downloaded 1199 bytes. 12:15:30 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 12:15:30 INFO - Using the following test package requirements: 12:15:30 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 12:15:30 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:15:30 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 12:15:30 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:15:30 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 12:15:30 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:15:30 INFO - u'jsshell-mac.zip'], 12:15:30 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:15:30 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 12:15:30 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 12:15:30 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:15:30 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 12:15:30 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:15:30 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 12:15:30 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:15:30 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 12:15:30 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:15:30 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 12:15:30 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:15:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 12:15:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:15:30 INFO - Fetch https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 12:15:31 INFO - Http code: 200 12:15:31 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 12:15:31 INFO - x-amz-cf-id: Kbcx6ih5ClTlcqq0jK1nEHF8s86QlxEHntnfqMS1tV_m4MW14n4IpQ== 12:15:31 INFO - x-amz-version-id: 11keKagFgPqqFh0xYYnUlHeyh0m0DwIT 12:15:31 INFO - Expected file size: 16275878 12:15:31 INFO - Obtained file size: 16275878 12:15:38 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 12:15:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 12:15:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:15:38 INFO - Fetch https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 12:15:38 INFO - Http code: 200 12:15:38 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 12:15:38 INFO - x-amz-cf-id: fLbSFjXVxBUGYScxBNxMJz-Lt9ICy9ZcTv-cY6OPDdNsunM5SZz7tw== 12:15:38 INFO - x-amz-version-id: 11keKagFgPqqFh0xYYnUlHeyh0m0DwIT 12:15:39 INFO - Expected file size: 16275878 12:15:39 INFO - Obtained file size: 16275878 12:15:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 12:15:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:15:45 INFO - Fetch https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 12:15:46 INFO - Http code: 200 12:15:46 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 12:15:46 INFO - x-amz-cf-id: 2nB-PSn_bG2JPUo6sFPffy1twydN3TcSgAQR6KZeHCOD8cPdvIdYrA== 12:15:46 INFO - x-amz-version-id: VY7Ic_NXdR_nmk4WMjHTSpCWUcgKqIq7 12:15:46 INFO - Expected file size: 14409038 12:15:46 INFO - Obtained file size: 14409038 12:15:46 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:15:46 INFO - trying https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:15:46 INFO - Downloading https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 12:15:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 12:15:49 INFO - Downloaded 110003433 bytes. 12:15:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:15:49 INFO - mkdir: /builds/slave/test/properties 12:15:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:15:49 INFO - Writing to file /builds/slave/test/properties/build_url 12:15:49 INFO - Contents: 12:15:49 INFO - build_url:https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:15:50 INFO - Running post-action listener: _resource_record_post_action 12:15:50 INFO - Running post-action listener: set_extra_try_arguments 12:15:50 INFO - [mozharness: 2017-01-17 20:15:50.462274Z] Finished download-and-extract step (success) 12:15:50 INFO - [mozharness: 2017-01-17 20:15:50.462380Z] Running populate-webroot step. 12:15:50 INFO - Running pre-action listener: _resource_record_pre_action 12:15:50 INFO - Running main action method: populate_webroot 12:15:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:15:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:15:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:15:50 INFO - '--tppagecycles', 12:15:50 INFO - '7'], 12:15:50 INFO - 'tests': ['tsvgm']}}, 12:15:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:15:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:15:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:15:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:15:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:15:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:15:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:15:50 INFO - 'talos_options': ['--disable-e10s'], 12:15:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:15:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:15:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:15:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:15:50 INFO - 'talos_options': ['--disable-e10s'], 12:15:50 INFO - 'tests': ['damp', 'tps']}, 12:15:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:15:50 INFO - 'tests': ['damp', 'tps']}, 12:15:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:15:50 INFO - 'tests': ['dromaeo_dom']}, 12:15:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:15:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:15:50 INFO - 'tests': ['basic_compositor_video']}, 12:15:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:15:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:15:50 INFO - 'tests': ['a11yr', 12:15:50 INFO - 'ts_paint', 12:15:50 INFO - 'tpaint', 12:15:50 INFO - 'sessionrestore', 12:15:50 INFO - 'sessionrestore_no_auto_restore', 12:15:50 INFO - 'tabpaint']}, 12:15:50 INFO - 'other-e10s': {'tests': ['a11yr', 12:15:50 INFO - 'ts_paint', 12:15:50 INFO - 'tpaint', 12:15:50 INFO - 'sessionrestore', 12:15:50 INFO - 'sessionrestore_no_auto_restore', 12:15:50 INFO - 'tabpaint']}, 12:15:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:15:50 INFO - 'ts_paint', 12:15:50 INFO - 'tpaint', 12:15:50 INFO - 'sessionrestore', 12:15:50 INFO - 'sessionrestore_no_auto_restore', 12:15:50 INFO - 'tabpaint']}, 12:15:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:15:50 INFO - 'ts_paint', 12:15:50 INFO - 'tpaint', 12:15:50 INFO - 'sessionrestore', 12:15:50 INFO - 'sessionrestore_no_auto_restore', 12:15:50 INFO - 'tabpaint']}, 12:15:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:15:50 INFO - 'tests': ['a11yr', 12:15:50 INFO - 'ts_paint', 12:15:50 INFO - 'tpaint', 12:15:50 INFO - 'sessionrestore', 12:15:50 INFO - 'sessionrestore_no_auto_restore', 12:15:50 INFO - 'tabpaint']}, 12:15:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:15:50 INFO - 'tests': ['a11yr', 12:15:50 INFO - 'ts_paint', 12:15:50 INFO - 'tpaint', 12:15:50 INFO - 'sessionrestore', 12:15:50 INFO - 'sessionrestore_no_auto_restore', 12:15:50 INFO - 'tabpaint']}, 12:15:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:15:50 INFO - 'tests': ['tsvgx', 12:15:50 INFO - 'tsvgr_opacity', 12:15:50 INFO - 'tart', 12:15:50 INFO - 'tscrollx', 12:15:50 INFO - 'cart']}, 12:15:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:15:50 INFO - 'tsvgr_opacity', 12:15:50 INFO - 'tart', 12:15:50 INFO - 'tscrollx', 12:15:50 INFO - 'cart']}, 12:15:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:15:50 INFO - 'talos_options': ['--disable-e10s'], 12:15:50 INFO - 'tests': ['tp5o']}, 12:15:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:15:50 INFO - 'tests': ['tp5o']}, 12:15:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:15:50 INFO - 'talos_options': ['--disable-e10s', 12:15:50 INFO - '--xperf_path', 12:15:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:15:50 INFO - 'tests': ['tp5n']}, 12:15:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:15:50 INFO - 'talos_options': ['--xperf_path', 12:15:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:15:50 INFO - 'tests': ['tp5n']}}, 12:15:50 INFO - 'talos.zip': {'path': '', 12:15:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:15:50 INFO - Running post-action listener: _resource_record_post_action 12:15:50 INFO - [mozharness: 2017-01-17 20:15:50.481228Z] Finished populate-webroot step (success) 12:15:50 INFO - [mozharness: 2017-01-17 20:15:50.481359Z] Running create-virtualenv step. 12:15:50 INFO - Running pre-action listener: _resource_record_pre_action 12:15:50 INFO - Running main action method: create_virtualenv 12:15:50 INFO - Creating virtualenv /builds/slave/test/build/venv 12:15:50 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:15:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:15:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:15:50 INFO - Using real prefix '/tools/python27' 12:15:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:15:51 INFO - Installing distribute.............................................................................................................................................................................................done. 12:15:54 INFO - Installing pip.................done. 12:15:54 INFO - Return code: 0 12:15:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:15:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:15:54 INFO - Reading from file tmpfile_stdout 12:15:54 INFO - Output received: 12:15:54 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:15:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:15: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:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:15:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:15: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:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:15:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:15: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109103490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1dad9de20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f17d30>, '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 0x108d7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f0fb20>, '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.TfVsuxYWqJ/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.CN1NYwHi9d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:15: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', 'pip>=1.5'] in /builds/slave/test/build 12:15: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 pip>=1.5 12:15:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TfVsuxYWqJ/Render', 12:15:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:15:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:15:54 INFO - 'HOME': '/Users/cltbld', 12:15:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:15:54 INFO - 'LOGNAME': 'cltbld', 12:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:15:54 INFO - 'MOZ_NO_REMOTE': '1', 12:15:54 INFO - 'NO_EM_RESTART': '1', 12:15:54 INFO - 'PAGER': '/bin/cat', 12:15:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:15:54 INFO - 'PWD': '/builds/slave/test', 12:15:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:15:54 INFO - 'SHELL': '/bin/bash', 12:15:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CN1NYwHi9d/Listeners', 12:15:54 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 12:15:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:15:54 INFO - 'USER': 'cltbld', 12:15:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:15:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:15:54 INFO - 'XPC_FLAGS': '0x0', 12:15:54 INFO - 'XPC_SERVICE_NAME': '0', 12:15:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:15:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:15:54 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:15:54 INFO - Cleaning up... 12:15:54 INFO - Return code: 0 12:15:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:15: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:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:15:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:15: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:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:15:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:15: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', '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 0x109103490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1dad9de20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f17d30>, '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 0x108d7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f0fb20>, '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.TfVsuxYWqJ/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.CN1NYwHi9d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:15: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', 'psutil>=3.1.1'] in /builds/slave/test/build 12:15: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 psutil>=3.1.1 12:15:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TfVsuxYWqJ/Render', 12:15:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:15:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:15:54 INFO - 'HOME': '/Users/cltbld', 12:15:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:15:54 INFO - 'LOGNAME': 'cltbld', 12:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:15:54 INFO - 'MOZ_NO_REMOTE': '1', 12:15:54 INFO - 'NO_EM_RESTART': '1', 12:15:54 INFO - 'PAGER': '/bin/cat', 12:15:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:15:54 INFO - 'PWD': '/builds/slave/test', 12:15:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:15:54 INFO - 'SHELL': '/bin/bash', 12:15:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CN1NYwHi9d/Listeners', 12:15:54 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 12:15:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:15:54 INFO - 'USER': 'cltbld', 12:15:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:15:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:15:54 INFO - 'XPC_FLAGS': '0x0', 12:15:54 INFO - 'XPC_SERVICE_NAME': '0', 12:15:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:15:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:15:54 INFO - Downloading/unpacking psutil>=3.1.1 12:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:15:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:15:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:15:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:15:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:15:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:15:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:15:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:15:58 INFO - Installing collected packages: psutil 12:15:58 INFO - Running setup.py install for psutil 12:15:58 INFO - building 'psutil._psutil_osx' extension 12:15:58 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:15:59 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:15:59 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:15:59 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:15:59 INFO - building 'psutil._psutil_posix' extension 12:15:59 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:15:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:15:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:15:59 INFO - ^ 12:15:59 INFO - 1 warning generated. 12:15:59 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:15:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:15:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:15:59 INFO - Successfully installed psutil 12:15:59 INFO - Cleaning up... 12:15:59 INFO - Return code: 0 12:15:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:15:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:15:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:15:59 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 0x109103490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1dad9de20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f17d30>, '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 0x108d7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f0fb20>, '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.TfVsuxYWqJ/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.CN1NYwHi9d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:15:59 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:15:59 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:15:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TfVsuxYWqJ/Render', 12:15:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:15:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:15:59 INFO - 'HOME': '/Users/cltbld', 12:15:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:15:59 INFO - 'LOGNAME': 'cltbld', 12:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:15:59 INFO - 'MOZ_NO_REMOTE': '1', 12:15:59 INFO - 'NO_EM_RESTART': '1', 12:15:59 INFO - 'PAGER': '/bin/cat', 12:15:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:15:59 INFO - 'PWD': '/builds/slave/test', 12:15:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:15:59 INFO - 'SHELL': '/bin/bash', 12:15:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CN1NYwHi9d/Listeners', 12:15:59 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 12:15:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:15:59 INFO - 'USER': 'cltbld', 12:15:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:15:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:15:59 INFO - 'XPC_FLAGS': '0x0', 12:15:59 INFO - 'XPC_SERVICE_NAME': '0', 12:15:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:16:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:16:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:16:02 INFO - Installing collected packages: mozsystemmonitor 12:16:02 INFO - Running setup.py install for mozsystemmonitor 12:16:02 INFO - Successfully installed mozsystemmonitor 12:16:02 INFO - Cleaning up... 12:16:03 INFO - Return code: 0 12:16:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:16:03 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:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:03 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:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:03 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 0x109103490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1dad9de20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f17d30>, '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 0x108d7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f0fb20>, '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.TfVsuxYWqJ/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.CN1NYwHi9d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:16:03 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:16:03 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:16:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TfVsuxYWqJ/Render', 12:16:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:03 INFO - 'HOME': '/Users/cltbld', 12:16:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:03 INFO - 'LOGNAME': 'cltbld', 12:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:03 INFO - 'MOZ_NO_REMOTE': '1', 12:16:03 INFO - 'NO_EM_RESTART': '1', 12:16:03 INFO - 'PAGER': '/bin/cat', 12:16:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:03 INFO - 'PWD': '/builds/slave/test', 12:16:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:03 INFO - 'SHELL': '/bin/bash', 12:16:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CN1NYwHi9d/Listeners', 12:16:03 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 12:16:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:03 INFO - 'USER': 'cltbld', 12:16:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:03 INFO - 'XPC_FLAGS': '0x0', 12:16:03 INFO - 'XPC_SERVICE_NAME': '0', 12:16:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:03 INFO - Downloading/unpacking jsonschema==2.5.1 12:16:03 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:16:03 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:16:03 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:16:03 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:16:03 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:16:03 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:16:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:16:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:16:05 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:16:05 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:16:05 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:16:05 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:16:05 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:16:05 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:16:06 INFO - Downloading functools32-3.2.3-2.tar.gz 12:16:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:16:06 INFO - warning: no files found matching '*.txt' 12:16:06 INFO - no previously-included directories found matching 'build' 12:16:06 INFO - no previously-included directories found matching 'dist' 12:16:06 INFO - no previously-included directories found matching '.git*' 12:16:06 INFO - Installing collected packages: jsonschema, functools32 12:16:06 INFO - Running setup.py install for functools32 12:16:06 INFO - warning: no files found matching '*.txt' 12:16:06 INFO - no previously-included directories found matching 'build' 12:16:06 INFO - no previously-included directories found matching 'dist' 12:16:06 INFO - no previously-included directories found matching '.git*' 12:16:06 INFO - Successfully installed jsonschema functools32 12:16:06 INFO - Cleaning up... 12:16:06 INFO - Return code: 0 12:16:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:16: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:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16: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:16:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:06 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 0x109103490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1dad9de20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f17d30>, '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 0x108d7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f0fb20>, '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.TfVsuxYWqJ/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.CN1NYwHi9d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:16:06 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:16:06 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:16:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TfVsuxYWqJ/Render', 12:16:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:06 INFO - 'HOME': '/Users/cltbld', 12:16:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:06 INFO - 'LOGNAME': 'cltbld', 12:16:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:06 INFO - 'MOZ_NO_REMOTE': '1', 12:16:06 INFO - 'NO_EM_RESTART': '1', 12:16:06 INFO - 'PAGER': '/bin/cat', 12:16:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:06 INFO - 'PWD': '/builds/slave/test', 12:16:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:06 INFO - 'SHELL': '/bin/bash', 12:16:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CN1NYwHi9d/Listeners', 12:16:06 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 12:16:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:06 INFO - 'USER': 'cltbld', 12:16:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:06 INFO - 'XPC_FLAGS': '0x0', 12:16:06 INFO - 'XPC_SERVICE_NAME': '0', 12:16:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:16:07 INFO - Cleaning up... 12:16:07 INFO - Return code: 0 12:16:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:16:07 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:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:07 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:16:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:07 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 0x109103490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1dad9de20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f17d30>, '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 0x108d7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f0fb20>, '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.TfVsuxYWqJ/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.CN1NYwHi9d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:16:07 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:16:07 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:16:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TfVsuxYWqJ/Render', 12:16:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:07 INFO - 'HOME': '/Users/cltbld', 12:16:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:07 INFO - 'LOGNAME': 'cltbld', 12:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:07 INFO - 'MOZ_NO_REMOTE': '1', 12:16:07 INFO - 'NO_EM_RESTART': '1', 12:16:07 INFO - 'PAGER': '/bin/cat', 12:16:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:07 INFO - 'PWD': '/builds/slave/test', 12:16:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:07 INFO - 'SHELL': '/bin/bash', 12:16:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CN1NYwHi9d/Listeners', 12:16:07 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 12:16:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:07 INFO - 'USER': 'cltbld', 12:16:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:07 INFO - 'XPC_FLAGS': '0x0', 12:16:07 INFO - 'XPC_SERVICE_NAME': '0', 12:16:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:07 INFO - Downloading/unpacking blobuploader==1.2.4 12:16:07 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:16:07 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:16:07 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:16:07 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:16:07 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:16:07 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:16:09 INFO - Downloading blobuploader-1.2.4.tar.gz 12:16:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:16:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:16:10 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:16:10 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:16:10 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:16:10 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:16:10 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:16:10 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:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:16:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:16:10 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:16:10 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:16:10 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:16:10 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:16:10 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:16:10 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:16:11 INFO - Downloading docopt-0.6.1.tar.gz 12:16:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:16:11 INFO - Installing collected packages: blobuploader, requests, docopt 12:16:11 INFO - Running setup.py install for blobuploader 12:16:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:16:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:16:11 INFO - Running setup.py install for requests 12:16:11 INFO - Running setup.py install for docopt 12:16:12 INFO - Successfully installed blobuploader requests docopt 12:16:12 INFO - Cleaning up... 12:16:12 INFO - Return code: 0 12:16:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:16: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:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16: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:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:12 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 0x109103490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1dad9de20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f17d30>, '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 0x108d7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f0fb20>, '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.TfVsuxYWqJ/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.CN1NYwHi9d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:16:12 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:16:12 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:16:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TfVsuxYWqJ/Render', 12:16:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:12 INFO - 'HOME': '/Users/cltbld', 12:16:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:12 INFO - 'LOGNAME': 'cltbld', 12:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:12 INFO - 'MOZ_NO_REMOTE': '1', 12:16:12 INFO - 'NO_EM_RESTART': '1', 12:16:12 INFO - 'PAGER': '/bin/cat', 12:16:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:12 INFO - 'PWD': '/builds/slave/test', 12:16:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:12 INFO - 'SHELL': '/bin/bash', 12:16:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CN1NYwHi9d/Listeners', 12:16:12 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 12:16:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:12 INFO - 'USER': 'cltbld', 12:16:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:12 INFO - 'XPC_FLAGS': '0x0', 12:16:12 INFO - 'XPC_SERVICE_NAME': '0', 12:16:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:16:12 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-yn4qWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:16:12 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-4frquP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:16:12 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-2p4ZnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:16:12 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-0HBvVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:16:12 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-8RNpNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:16:12 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-A2HYwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:16:12 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-xs8h2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:16:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-9O2C0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:16:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-vp71N1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:16:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-RylZxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:16:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-4nDqOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:16:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-yTIAwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:16:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-ckrHTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:16:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-Bf0VrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:16:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-b_bzHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:16:13 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-Zp_2br-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:16:14 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-s4teqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:16:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:16:14 INFO - Running setup.py install for manifestparser 12:16:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:16:14 INFO - Running setup.py install for mozcrash 12:16:14 INFO - Running setup.py install for mozdebug 12:16:14 INFO - Running setup.py install for mozdevice 12:16:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:16:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:16:14 INFO - Running setup.py install for mozfile 12:16:14 INFO - Running setup.py install for mozhttpd 12:16:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:16:15 INFO - Running setup.py install for mozinfo 12:16:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:16:15 INFO - Running setup.py install for mozInstall 12:16:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:16:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:16:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:16:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:16:15 INFO - Running setup.py install for mozleak 12:16:15 INFO - Running setup.py install for mozlog 12:16:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:16:15 INFO - Running setup.py install for moznetwork 12:16:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:16:15 INFO - Running setup.py install for mozprocess 12:16:15 INFO - Running setup.py install for mozprofile 12:16:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:16:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:16:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:16:16 INFO - Running setup.py install for mozrunner 12:16:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:16:16 INFO - Running setup.py install for mozscreenshot 12:16:16 INFO - Running setup.py install for moztest 12:16:16 INFO - Running setup.py install for mozversion 12:16:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:16:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:16:16 INFO - Cleaning up... 12:16:16 INFO - Return code: 0 12:16:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:16 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 0x109103490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1dad9de20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f17d30>, '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 0x108d7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f0fb20>, '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.TfVsuxYWqJ/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.CN1NYwHi9d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:16:16 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:16:16 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:16:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TfVsuxYWqJ/Render', 12:16:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:16 INFO - 'HOME': '/Users/cltbld', 12:16:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:16 INFO - 'LOGNAME': 'cltbld', 12:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:16 INFO - 'MOZ_NO_REMOTE': '1', 12:16:16 INFO - 'NO_EM_RESTART': '1', 12:16:16 INFO - 'PAGER': '/bin/cat', 12:16:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:16 INFO - 'PWD': '/builds/slave/test', 12:16:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:16 INFO - 'SHELL': '/bin/bash', 12:16:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CN1NYwHi9d/Listeners', 12:16:16 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 12:16:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:16 INFO - 'USER': 'cltbld', 12:16:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:16 INFO - 'XPC_FLAGS': '0x0', 12:16:16 INFO - 'XPC_SERVICE_NAME': '0', 12:16:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:16:16 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-VK4jPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:16:17 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:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:16:17 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-N1ZW1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:16:17 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:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:16:17 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-sqlLI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:16:17 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:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:16:17 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-zUUW5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:16:17 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:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:16:17 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-ducgSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:16:17 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:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:16:17 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-dBVr1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:16:17 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:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:16:17 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-2m_YSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:16:17 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:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:16:17 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-JvTie8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:16:17 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:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:16:17 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-AQ8Fpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:16:17 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:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:16:17 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-q1ZyoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:16:18 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:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:16:18 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-F9qDz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:16:18 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:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:16:18 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-zFJ9SF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:16:18 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:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:16:18 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-ugkHUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:16:18 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:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:16:18 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-YedOh2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:16:18 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:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:16:18 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-Ig73DO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:16:18 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:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:16:18 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-GOrrIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:16:18 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:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:16:18 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-bQUxag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:16:18 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:16:18 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:16:18 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:16:18 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:16:18 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:16:18 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:16:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:16:18 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:16:18 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:16:18 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:16:18 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:16:18 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:16:18 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:16:21 INFO - Downloading blessings-1.6.tar.gz 12:16:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:16:21 INFO - Installing collected packages: blessings 12:16:21 INFO - Running setup.py install for blessings 12:16:21 INFO - Successfully installed blessings 12:16:21 INFO - Cleaning up... 12:16:22 INFO - Return code: 0 12:16:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:16:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:16:22 INFO - Reading from file tmpfile_stdout 12:16:22 INFO - Current package versions: 12:16:22 INFO - blessings == 1.6 12:16:22 INFO - blobuploader == 1.2.4 12:16:22 INFO - docopt == 0.6.1 12:16:22 INFO - functools32 == 3.2.3-2 12:16:22 INFO - jsonschema == 2.5.1 12:16:22 INFO - manifestparser == 1.1 12:16:22 INFO - mozInstall == 1.12 12:16:22 INFO - mozcrash == 1.0 12:16:22 INFO - mozdebug == 0.1 12:16:22 INFO - mozdevice == 0.48 12:16:22 INFO - mozfile == 1.2 12:16:22 INFO - mozhttpd == 0.7 12:16:22 INFO - mozinfo == 0.9 12:16:22 INFO - mozleak == 0.1 12:16:22 INFO - mozlog == 3.4 12:16:22 INFO - moznetwork == 0.27 12:16:22 INFO - mozprocess == 0.23 12:16:22 INFO - mozprofile == 0.28 12:16:22 INFO - mozrunner == 6.13 12:16:22 INFO - mozscreenshot == 0.1 12:16:22 INFO - mozsystemmonitor == 0.3 12:16:22 INFO - moztest == 0.8 12:16:22 INFO - mozversion == 1.4 12:16:22 INFO - psutil == 3.1.1 12:16:22 INFO - requests == 1.2.3 12:16:22 INFO - wsgiref == 0.1.2 12:16:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:22 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 0x109103490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1dad9de20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f17d30>, '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 0x108d7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f0fb20>, '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.TfVsuxYWqJ/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.CN1NYwHi9d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:16:22 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:16:22 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:16:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TfVsuxYWqJ/Render', 12:16:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:22 INFO - 'HOME': '/Users/cltbld', 12:16:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:22 INFO - 'LOGNAME': 'cltbld', 12:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:22 INFO - 'MOZ_NO_REMOTE': '1', 12:16:22 INFO - 'NO_EM_RESTART': '1', 12:16:22 INFO - 'PAGER': '/bin/cat', 12:16:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:22 INFO - 'PWD': '/builds/slave/test', 12:16:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:22 INFO - 'SHELL': '/bin/bash', 12:16:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CN1NYwHi9d/Listeners', 12:16:22 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 12:16:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:22 INFO - 'USER': 'cltbld', 12:16:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:22 INFO - 'XPC_FLAGS': '0x0', 12:16:22 INFO - 'XPC_SERVICE_NAME': '0', 12:16:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:22 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:16:22 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:16:22 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:16:22 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:16:22 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:16:22 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:16:22 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:16:22 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:16:22 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:16:22 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:16:22 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:16:22 INFO - Cleaning up... 12:16:23 INFO - Return code: 0 12:16:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:23 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 0x109103490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1dad9de20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f17d30>, '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 0x108d7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f0fb20>, '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.TfVsuxYWqJ/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.CN1NYwHi9d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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:16:23 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:16:23 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:16:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TfVsuxYWqJ/Render', 12:16:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:23 INFO - 'HOME': '/Users/cltbld', 12:16:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:23 INFO - 'LOGNAME': 'cltbld', 12:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:23 INFO - 'MOZ_NO_REMOTE': '1', 12:16:23 INFO - 'NO_EM_RESTART': '1', 12:16:23 INFO - 'PAGER': '/bin/cat', 12:16:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:23 INFO - 'PWD': '/builds/slave/test', 12:16:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:23 INFO - 'SHELL': '/bin/bash', 12:16:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CN1NYwHi9d/Listeners', 12:16:23 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 12:16:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:23 INFO - 'USER': 'cltbld', 12:16:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:23 INFO - 'XPC_FLAGS': '0x0', 12:16:23 INFO - 'XPC_SERVICE_NAME': '0', 12:16:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:16:23 INFO - Cleaning up... 12:16:23 INFO - Return code: 0 12:16:23 INFO - Running post-action listener: _resource_record_post_action 12:16:23 INFO - Running post-action listener: _start_resource_monitoring 12:16:23 INFO - Starting resource monitoring. 12:16:23 INFO - [mozharness: 2017-01-17 20:16:23.299597Z] Finished create-virtualenv step (success) 12:16:23 INFO - [mozharness: 2017-01-17 20:16:23.300642Z] Running install step. 12:16:23 INFO - Running pre-action listener: _resource_record_pre_action 12:16:23 INFO - Running main action method: install 12:16:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:16:23 INFO - Reading from file tmpfile_stdout 12:16:23 INFO - Detecting whether we're running mozinstall >=1.0... 12:16:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:16:23 INFO - Reading from file tmpfile_stdout 12:16:23 INFO - Output received: 12:16:23 INFO - Usage: mozinstall [options] installer 12:16:23 INFO - Options: 12:16:23 INFO - -h, --help show this help message and exit 12:16:23 INFO - -d DEST, --destination=DEST 12:16:23 INFO - Directory to install application into. [default: 12:16:23 INFO - "/builds/slave/test"] 12:16:23 INFO - --app=APP Application being installed. [default: firefox] 12:16:23 INFO - mkdir: /builds/slave/test/build/application 12:16:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 12:16:55 INFO - Reading from file tmpfile_stdout 12:16:55 INFO - Output received: 12:16:55 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:16:55 INFO - Running post-action listener: _resource_record_post_action 12:16:55 INFO - [mozharness: 2017-01-17 20:16:55.007434Z] Finished install step (success) 12:16:55 INFO - [mozharness: 2017-01-17 20:16:55.007588Z] Running run-tests step. 12:16:55 INFO - Running pre-action listener: _resource_record_pre_action 12:16:55 INFO - Running main action method: run_tests 12:16:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:16:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:16:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:16:55 INFO - 2017-01-17 12:16:55.021 screenresolution[1620:7515] starting screenresolution argv=screenresolution get 12:16:55 INFO - 2017-01-17 12:16:55.024 screenresolution[1620:7515] Display 0: 1600x1200x32@60 12:16:55 INFO - 2017-01-17 12:16:55.030 screenresolution[1621:7518] starting screenresolution argv=screenresolution list 12:16:55 INFO - Available Modes on Display 0 12:16:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:16:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:16:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:16:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:16:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:16:55 INFO - Intel Iris: 12:16:55 INFO - Chipset Model: Intel Iris 12:16:55 INFO - Type: GPU 12:16:55 INFO - Bus: Built-In 12:16:55 INFO - VRAM (Dynamic, Max): 1536 MB 12:16:55 INFO - Vendor: Intel (0x8086) 12:16:55 INFO - Device ID: 0x0a2e 12:16:55 INFO - Revision ID: 0x0009 12:16:55 INFO - Displays: 12:16:55 INFO - Display: 12:16:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:16:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:16:55 INFO - Display Serial Number: 1600x1200 60 12:16:55 INFO - Main Display: Yes 12:16:55 INFO - Mirror: Off 12:16:55 INFO - Online: Yes 12:16:55 INFO - Rotation: Supported 12:16:55 INFO - Return code: 0 12:16:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:16:55 INFO - Python 2.7.3 12:16:55 INFO - Return code: 0 12:16:55 INFO - grabbing minidump binary from tooltool 12:16:55 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:16:55 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 0x108f17d30>, '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 0x108d7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f0fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:16:55 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:16:55 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:16:55 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:16:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:16:55 INFO - Return code: 0 12:16:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:16:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:16:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:16:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:16:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:16:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0165', '--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:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0165 --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:16:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TfVsuxYWqJ/Render', 12:16:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:55 INFO - 'HOME': '/Users/cltbld', 12:16:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:55 INFO - 'LOGNAME': 'cltbld', 12:16:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:16:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:55 INFO - 'MOZ_NO_REMOTE': '1', 12:16:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:16:55 INFO - 'NO_EM_RESTART': '1', 12:16:55 INFO - 'PAGER': '/bin/cat', 12:16:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:55 INFO - 'PWD': '/builds/slave/test', 12:16:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:16:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:55 INFO - 'SHELL': '/bin/bash', 12:16:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CN1NYwHi9d/Listeners', 12:16:55 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 12:16:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:55 INFO - 'USER': 'cltbld', 12:16:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:55 INFO - 'XPC_FLAGS': '0x0', 12:16:55 INFO - 'XPC_SERVICE_NAME': '0', 12:16:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0165', '--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:16:55 INFO - mozversion application_buildid: 20170117065523 12:16:55 INFO - mozversion application_changeset: 2356029b0bb5085b468d0c6a1acc58e8c664fb12 12:16:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:16:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:16:55 INFO - mozversion application_name: Firefox 12:16:55 INFO - mozversion application_remotingname: firefox-dev 12:16:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:16:55 INFO - mozversion application_vendor: Mozilla 12:16:55 INFO - mozversion application_version: 52.0a2 12:16:55 INFO - mozversion platform_buildid: 20170117065523 12:16:55 INFO - mozversion platform_changeset: 2356029b0bb5085b468d0c6a1acc58e8c664fb12 12:16:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:16:55 INFO - mozversion platform_version: 52.0a2 12:16:55 INFO - using testdate: 1484684215 12:16:55 INFO - actual date: 1484684215 12:16:55 INFO - starting webserver on 'localhost:49242' 12:16:55 INFO - SUITE-START | Running 2 tests 12:16:55 INFO - TEST-START | dromaeo_css 12:16:55 INFO - operating with platform_type : mac_ 12:16:55 INFO - Initialising browser for dromaeo_css test... 12:16:55 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/tmpSn9NMt/profile http://localhost:49242/getInfo.html) 12:16:58 INFO - PROCESS | 1631 | 2017-01-17 12:16:58.732 plugin-container[1633:7699] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x474f, name = 'com.apple.tsm.portname' 12:16:58 INFO - PROCESS | 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:16:58 INFO - PROCESS | 1631 | 2017-01-17 12:16:58.734 plugin-container[1633:7699] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 12:16:58 INFO - PROCESS | 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:16:58 INFO - PROCESS | 1631 | 2017-01-17 12:16:58.735 plugin-container[1633:7699] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:16:58 INFO - PROCESS | 1631 | __metrics Screen width/height:1600/1200 12:16:58 INFO - PROCESS | 1631 | colorDepth:24 12:16:58 INFO - PROCESS | 1631 | Browser inner width/height: 1024/689 12:16:58 INFO - PROCESS | 1631 | __metrics 12:16:58 ERROR - PROCESS | 1631 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 12:16:59 INFO - TEST-INFO | 1631: exit 0 12:16:59 INFO - Browser initialized. 12:16:59 INFO - Running cycle 1/1 for dromaeo_css test... 12:16:59 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/tmpSn9NMt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:17:02 INFO - PROCESS | 1634 | 2017-01-17 12:17:02.546 plugin-container[1635:7829] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 12:17:02 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:17:02 INFO - PROCESS | 1634 | 2017-01-17 12:17:02.546 plugin-container[1635:7829] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 12:17:02 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:17:02 INFO - PROCESS | 1634 | 2017-01-17 12:17:02.546 plugin-container[1635:7829] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:17:12 INFO - PROCESS | 1634 | RSS: Main: 228990976 12:17:12 INFO - PROCESS | 1634 | 12:20:14 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 12:20:16 INFO - PROCESS | 1634 | RSS: Main: 233754624 12:20:16 INFO - PROCESS | 1634 | 12:23:18 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 12:23:19 INFO - PROCESS | 1634 | RSS: Main: 246927360 12:23:19 INFO - PROCESS | 1634 | 12:26:21 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 12:26:22 INFO - PROCESS | 1634 | RSS: Main: 249995264 12:26:22 INFO - PROCESS | 1634 | 12:29:23 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 12:29:24 INFO - PROCESS | 1634 | RSS: Main: 249352192 12:29:24 INFO - PROCESS | 1634 | 12:32:27 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 12:32:27 INFO - PROCESS | 1634 | RSS: Main: 252813312 12:32:27 INFO - PROCESS | 1634 | 12:35:30 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 12:35:30 INFO - PROCESS | 1634 | RSS: Main: 254009344 12:35:30 INFO - PROCESS | 1634 | 12:35:30 INFO - PROCESS | 1634 | __start_tp_report 12:35:30 INFO - PROCESS | 1634 | _x_x_mozilla_page_load 12:35:30 INFO - PROCESS | 1634 | _x_x_mozilla_page_load_details 12:35:30 INFO - PROCESS | 1634 | |i|pagename|runs| 12:35:30 INFO - PROCESS | 1634 | |0;dojo.html;2733.6719593648763;2881.7176356521345;3397.065806903102;3425.777324473909;3438.6045604755445;5087.236914462833;5224.63427560071;5249.632525723201;5654;5717.142428635701;5673.546116310696;5728.198052272685;5735.397783232753;5752.165935939286;5799.9130013049735;4464.776761161929;5134.563562097226;5205.3233079699585;5235.214717792323;5238.083335416306;5229.346331708536;5255.527002569768;5261;5274.973625131888;5289;4283.357496375537;5062.063518249117;5128.4358720540895;5243.318368612075;5254.973725131369;5156.664816786915;5196.32447781786;5196.324477817897;5201.40183878855;5213.061648903196;4267.338562522814;5095.006473737622;5133.230015497668;5215.269862219292;5258.947410525922;4727.952720472786;5711.828645140611;5737.196792449061;5748.712564371806;5795.8551036224435;6030.487408570233;6089;6217.2539295284405;6242.219722534684;6243.687815609202;6120.904292401164;6131.59076170999;6188.10272510488;6188.226471691039;6201.472874805647;5135.666181698178;5490.956718223565;5753.856153596152;5774.566907481872;5846.7076646167525;5805.651660900275;6232.532560058014;6248.156498872685;6297.149884765498;6304.5586808922935;6034.5172386209;6050.092486127028;6069.423404776539;6132.386761323832;6152.261728592597;6052.57631965767;6124.014786914997;6129.419919118862;6130.856783744145;6133.82435864808;6143.481172719164;6148.631082135086;6154.599951003171;6157.538184636171;6174.38256174379;6071.574989750763;6083.540095152081;6110.406879924458;6136.355682653347;6212.223472065992;4863.465018847924;4909.631777616693;4922.606191504743;4924.113659541317;4942.481039490874;4370.431843860278;4385.473743150842;4410.25025745624;4423.049044455393;4461.866144015685;5604.159376093618;5824.708764561755;5832.88334233313;5856.326522449973;5875.882482350329;6092.3603021682975;6134.926457743726;6140.754369825247;6170.167027451236;6228.937710622927;5745.2818397700285;5840.474357307863;5848.853778655568;5855.443732845373;5877.9118313225335;5296.311479507639;5348.849997250532;5356.946430535645;5395.136778115482;5422.972885135628;6318.71565779541;6352.428281454599;6357.332480089616;6374.107624932421;6388.977763557808;6125.784909797561;6177.62934223948;6180.196574445294;6234.719437625227;6239.032949892774;5060.84817455477;6313.463355614824;6354.4281014708895;6369.7452101915405;6404.871902561829;4582.33556134352;6118.684482836212;6203.286622038523;6213.875722485615;6243.157173781481;5952.375000625046;6100.725467354068;6140.657627863787;6222.595531290452;6256.937430625636;6102.938970610237;6129.509639228941;6133.325334213144;6165.167702360124;6183.505319574513;6085.361037091218;6113.388661133851;6122.877542449037;6170.352113028068;6179.1040299155475;5757.683327417032;5860.648361098348;6005.90991135142;6011.729472173675;6037.396260373927;6047.183630209865;6080.452759251689;6082.635041897501;6124.050772130327;6181.227346581677;5949.970250148722;5989.970050149721;6016.939830601638;6020.789272375446;6081.665508397081 12:35:30 INFO - PROCESS | 1634 | |1;ext.html;476.24039656747476;484.85212010336846;491.0424671889811;503.85373275797565;543.6466296907013;36020;39769.40345894814;39852.601473985254;41934.79032604833;42032.789836050775;30467.84766076172;40538.18923621533;40624.39063414051;41123;41213.38179927296;9196.98833128357;9317.068293170696;9355.298352623546;9358.625654973794;9372.343935924488;9246.214071803905;9309.627614895397;9324.160825525702;9361.625534978593;9367.859482107773;9333.486658233795;9336.81326373472;9358.29812764042;9358.532073396336;9370.859437108415;9280.118388753057;9340.392874463138;9348.719538413858;9351.812963740687;9375.15623593873;9264.073592640681;9338.159565639056;9344.15902568772;9363.204127649158;9367.203787677987;38856.611433885584;38981.02547436309;39365.01587460308;39422.21155576872;39743.403848942;3315.287213249163;3426.9657303426898;3446.638102999199;3959.1289916218384;4145.191687620915;29140.854295728597;29418.558721619193;29434.705652943412;30030.98127221821;30901.227469313224;3024.5916801231774;3184.4268031754277;3186.123815950609;3219.8873039443506;3243.4810430331268;502649;505715;506160;507069.46465267806;508982;60499.69750151221;61016.779664406466;61240.08139877905;61592.69203653953;61725.45686357787;62855.685721572016;62928.685356572925;63088.684556576925;63142.684286578275;63228.68385658042;16224.188790560425;16250.918745406198;16513.174341283127;16526.586835329213;16551.420700275674;12606.495740170474;12668.556600518938;12672.239665620093;12692.80960785589;12695.936520317524;17935.197257231204;18570.071496425124;18650.031989763145;18666.626667466575;18706.345277915207;3233.8221397823345;6838.863222735517;7262.164851042197;7870.858725484887;8036.116027236999;880.9823803523893;921.7373048681094;926.1710768861831;946.4179529589253;1050.9438014795235;26382.927992370114;27457.588136177972;30274.54588181179;30345.393092138034;30544.236394090327;1027.9743006424899;1034.9793004139874;1092.2736380307008;1093.5024563823442;1107.2083460325744;15152.696946061016;15349.002314849722;15568.532944011698;15577.922110389376;15816.92091539558;692683;694526;700347;701599;704018;57070;57211.427885721445;57890.710546447;57957.71021144867;58046.8390632185;1332.4070788499218;1346.912450690702;1365.1877133105786;1374.4364810427876;1379.5102738527878;25661.973521059695;29229.392383419116;29839.70160298456;30289.394212116076;30420.239494012825;49062;49228.2615760771;49332.75333623453;49431;49446.50553494419;540195;540877;541650;541777;542352;21958;25829.741702582734;26114.86942565275;26560.203193904214;26615.06847260414;46124;46213.53786462092;46452.07095857996;46843;47160.820979475786;38571.421428679154;38616.80691596524;38627.80686096552;38815.80592097021;39119.41320880246;37603.435948461345;37918.052048699006;38646.6135338643;39246.01884952899;39396;36864.63135368612;37215.441768372955;37620.811895940344;37659.43510847395;37996;32783.34433311273;34244.82877585596;34430.13924651904;34697.45294461181;35056.47415288722;25535.23394298174;25771.613425798252;25960.610590840774;25991.830367633123;26094.956201751716 12:35:30 INFO - PROCESS | 1634 | |2;jquery.html;332.9450640644294;377.52620461321055;379.10342041072863;387.2526024772192;401.65859019833147;18945.621087578256;19419.41741747748;19767.91276479793;20109.99999999998;20327.983600819975;11535.077193824494;11548.307101573911;11980.760384792298;11992.220505667126;12040.217385869912;5316.548093412055;5530.640508366963;5572.832815015556;5576.470235327637;5601.3558440779425;3962.2669806085823;4175.227582897159;4790.898093438511;5433.864153396177;5495;4187.895302617429;6714.966425167891;6769;6788.049673045777;6797.796066118024;6482.189726284215;6657.46740260778;6701.128853249095;6710.463162946952;6762.628055456948;5907.7046147692445;5936.851578710523;5943.732532036025;5958.255218097738;5967.7911273105665;13089.41097650608;15204.619884502865;23596;24688.876555617288;24802.5039499209;2142.582196471689;2245.371296037112;2310.122153581628;2366.7396586375485;2692.0597133363053;10166.542505587266;10241.180705543538;10461.947690261575;10494.003069708442;14362.707356337874;1834.2112891456668;1840.6870831958465;1869.2149297295168;1878.821511956362;1894.8578856585673;76587.23412765856;115681.10558584477;119578;124785;137748;11479.598214062467;11706.414679266003;11807.645770626896;11823.113266505046;11966.64100076999;12339.197952133083;14952.177630230442;14997.925010374878;15008.324625391884;15025.323860426308;8407.873881891777;8506.74479765608;8576.05663377028;8612.267957223581;8710.52092134926;8384.413091083688;8450.87323690145;8457.788555286166;8543.188397102265;8587.871181932276;3792.355299599075;3796.430535419654;4561.178987782231;4615.607673347803;4781.091592597395;1922.7406049037902;1990.741203643517;2012.356046065245;2022.1405902491676;2034.3998520436508;362.49383810268375;404.3429427180831;689.9103116594911;830.9459885107449;913.7487191022522;11270.549178032787;11756.58851940178;11811.763764724657;12019.63941081769;12089;428.0347815675694;428.47939753199387;432.20042920597274;726.2918654312003;777.0597576931863;7633.465657403928;9183.219426348835;9924.70978772755;10452.268341216193;10551.472426378803;519741;583802;612047;623339;627513;122691.15963260559;129287.35356323158;137456;138165;145329.27335363466;927.9025702301161;993.4138858073627;1003.3879333606494;1006.8942761010135;1009.878814542245;15157.393704251706;18280.634387312446;18969.33607323737;19036;19114.426567203005;57210.42789572051;57389.85220295487;59446;59804;60110;54065;81535;82471.58764206141;82876.75684864611;91589.54205228931;7233.421326293778;7445.292697193758;7551.207123252199;7943.563104029334;8258.050324212792;9044.819103618023;12250.693732656755;13142.948564115039;13151.408186631434;13185.40665670028;4317.568243175658;5323.920141197807;10276.434796086287;10891.237613366988;11136.38749868765;10531;11034.117270618492;11164.665060048212;11401.942990285328;11585.246958947979;11308.151888608223;11411.201215914807;11523.711907202387;11659.708507287385;11739.47172377228;7298.96350518244;7393.8521229574035;7583.165851756192;7673.232676732282;7731.961340193263;10742.785144297226;11047.889521104686;11299.491522881326;11353.829692554453;11396.886031139582 12:35:30 INFO - PROCESS | 1634 | |3;mootools.html;1434.9426022959083;1476.335648957969;1476.9926150369247;1636.1819090454774;1691.890027148236;7489.887651685229;7763.301302882739;8004.03951525817;8490.066092729794;8523.105073967237;8010.5594192319395;8336;8349.081601023883;8431.62057707403;8484.278836298907;2303.677485152076;2324.651302304651;2344.0272287000876;2346.3078391874437;2354.152505098163;2350.27141586108;2369.5379401016808;2392.5932591459496;2398.0647547456506;2399.892004859777;2334.8015418689433;2341.426350544119;2355.6584295277175;2360.327306717586;2361.633946738258;2230.431240033781;2234.2850287907877;2250.246167533862;2252.3580779477934;2254.256095488493;2353.658719485665;2369.1115831563166;2370.2533701883854;2379.428937055094;2381.1070848431837;8407;8419.410641255115;8468.195521425454;8523.65905363791;8526.275266602284;4349.869503914888;4466.865994020184;4947.901041979177;5121.103806833827;5201.037808005531;3436.74224433166;3459.636738142484;3548.3258180945536;3607.6753092221566;3611.56661200655;1002.8867956568204;1026.2559644257929;1082.86464191976;1083.6044843632017;1101.564881871656;9209.815803683956;9824.459654718987;10102.646407375707;10360.94819525905;10969.451527423676;4055.3916912463364;4504.527024662429;4534.13851368239;4693.460252070987;4721.716696998191;9214.216791572791;9227.953860230657;9257.953710231406;9263.305252105984;9273;5161.045206636784;5162.922556161586;5166.095933211673;5173.379194496684;5192.143296356135;4264.530901600821;4412.911741765147;4637.605803506673;4746.406699162605;4750.691205071659;3824.6749026333073;3842.366009608439;3864.9806750966063;3927.7643341399607;3943.132510847609;1285.2673975833684;1295.9870401296055;1296.1121631682329;1306.4121145484569;1312.4618906248393;899.5412339706796;915.697819719491;925.7407925780792;927.4456682948141;940.210223412337;5687.755650434456;5781.248437703071;5810.6513609183585;6030.6080104793045;6043.365446628129;450.54720006393364;452.8958339581849;454.03744062587526;457.8420444946488;464.4705036258668;3904.355781296111;3908.4332771747936;3922.5096862892137;3984;4056.4320995060716;9900.306978511579;10114.29199955996;10258.17934565233;10286.588536458608;10311.968803119627;10081.949590252001;10112.342697724624;10161.847572286422;10188.286819922683;10235.539400726986;7575.962120189364;7808.921910780819;7955.4828936118965;7963.1638677939145;8058.758237252891;5792.565557583114;5840.795572154954;5866.149408335853;5962.612430192024;5983.132445795422;9346.392484488486;9374.484403357608;9411.81176376455;9532.428054316762;9557.139857412867;8987.370884038055;9374.906250937676;9443.244540436886;9676.661316853877;9754.75613109678;7241.855162896607;7243.167035790952;7250.093738282715;7290.234525374972;7333.889991650236;7827;7884.881726773988;7899.84200316002;7914.604269786603;7922.168172341821;6915.757948471779;7010.579365238102;7101.218865924641;7136.892946605909;7235.457340699363;6021.187139736079;6874.381305682513;7022.54353467023;7165;7207.351338379571;5258.421573626822;6483.805485835433;6710.597364158166;6797.72809087631;6823.4882383822405;4917.877053073702;4934.037862616755;4938.629602779734;4958.380202474691;4988.9251661225835;4285.250081235834;4763.095011947719;4935.555799978019;4995.100881841158;5031.547160755403 12:35:30 INFO - PROCESS | 1634 | |4;prototype.html;302.31978049388863;306.54478100021464;312.6966842163102;315.89575440104767;316.7529327124843;4493.483249426317;4543.795529201186;5057.924131138036;5261.605379596526;5558.1107022876395;4326.394304797331;5192.454792246816;5229.633925625208;5350.892982140354;5449.264349312842;4149.46057012588;4154.896127596804;4168.541460439349;4168.958310416887;4183.874483765492;4005.376478406634;4149.2531344358;4153.0065568527125;4162.542120366757;4204.957950420502;3214.2607200343937;3981.407437025184;4083.6528895043957;4099.282625540533;4124.278251306025;4070.653994410479;4085.1608132347883;4117.423560701504;4134.586541345841;4137.234611596864;2943.2936095337177;3556.590992035923;3608.0414060470425;3627.3470775260444;3634.56385233771;4551.469167067238;4580.106879158566;4990.72736452206;5020.297158397827;5295.814646487393;3955.7428767130045;4096.692748043879;4677.906441871178;4696.3894693689945;4725.786465203694;4770.117528257283;5587.161925711135;5668.263125793662;5719.59962802604;5764.394738552433;3781.565120011179;3791.8104094795426;3910.3156947534353;3931.174453364797;3955.8417663293435;5947.791827286068;6017.939820601781;6018.819435416901;6020.488258498033;6022.427869352405;4035.4955630546183;4035.636792688672;4051.959480405217;4057.0668746188544;4058.837646494166;3791.6208379161862;3823.9426408603895;3824.464574959508;4008.8396464141692;4112.732672376345;4112.485939257593;4404;4417.911641767147;4678.906421871544;4693.151615675114;5689.829305120853;5741.88516229673;5784.1902133701315;5790.888469186924;5794.449527294901;1092.6831218946575;1169.5789515774316;1200.099925056208;1262.9684257893625;1309.2602679486251;885.9778505537284;892.4511425473416;894.2488309819782;907.4101833808078;907.9636814527345;4463.575960283768;5129.589632829365;5174.404943431478;5180.6169162185815;5194.532492075731;4389.846355377546;4889.217725163957;5069.020818657336;5368.624196306299;5618.679610291578;4499.887502812456;4551.769632879931;4795.688280261772;5084.949150508522;5141.640085194075;2576.587745960675;2894.5079336512845;2924.239697678619;2939.1623387334507;2955.807872488281;4758.405934012076;5060.507150390644;5251.789928402821;5420.306774647745;5688.459596338341;4782.397896704643;4961.727105009187;5707.74315155819;5773.451522105393;5774.88450230993;4662.016309561883;5115.411727651293;5197.687029272013;5485.835424937259;5781.942180578309;4101.425800387889;5291.031726205346;5411.567074633941;5451.945480545144;5581.6371935824045;4500.887477813081;4518.819247230074;4605.723656580616;4679.3916790816975;5598.272224610774;5173.81891633806;5240.921386179134;5309.415964244007;5363.758630861698;5710.200571919851;2981.686922873067;3969.9801500993467;3972.503437070366;3973.7615743055508;3991.4212439196735;5930.8220753375745;5931.606072572856;5942.286925568959;5965.4929331005615;5980.581359304807;5398.7300634967305;5447.564194864322;5729.742161602821;5737.569682273763;5794.478496935128;4823.517648235148;5549.500544950813;5691;5694.572907031907;5748.252727145445;5051.449018245762;5052.095045348866;5390.568754499709;5503.669779813224;5616.634918730269;5674.177244299471;5760.782098891873;5764.423557644202;5770.884582308414;5773;5085.644004919768;5360.517553420211;5602.551795856403;5611.859703507282;5618.606697531133 12:35:30 INFO - PROCESS | 1634 | |5;yui.html;597.3071237364658;702.5960072958048;753.9246075392462;759.3090287838069;808.2200676347326;13828.377723002464;14420.495282665122;14795.038322509043;14833.258337083156;14974.32615532301;13224.338783060832;13377.464901403931;13464.46142154315;13486.865131348684;13542;669.8392385827403;679.9932000679979;840.315143158327;844.7634662294568;851.595492141231;831.8045259364075;833.204289903141;837.0457678246773;839.7816567692413;842.4987132656059;710.3926143147612;835.7534527314457;884.0496466298755;884.9955750221272;886.8625363068702;822.880682301069;855.4183155454289;858.8368210040104;876.7325965580495;880.6301353431575;632.512965016939;635.2377147423065;647.1522305779444;654.4829584628138;656.2879275985562;12386.876131238752;12483.937580312131;12498.375081245993;12505.3747312634;13701.725965480635;10796.460176991119;13082.411291491906;13168.123907069697;13204.537841175608;13269.270190139538;10639.98920102589;10973.451327433597;11244.550217991271;11326.490307936163;11367.09063274936;3268.117608245784;3523.5243242162237;3597.5503062117236;3751.4185301278344;3772.585015648304;89683;89703;89967.75080622971;90090.54954725184;90785.00682462462;10009.449480278654;10212.234082443847;10325.07074363296;10359.948200259101;10418.06237438619;16342.673146537003;16470.341186352653;17422.390216342366;19922.203111875362;19951.700724489143;6465.547411681231;7403.22266162056;7419.629018549051;7503.737369192052;7591.696332146652;4107.260693075275;4126.09225970292;4177.4569305990635;4198.6011328923705;4206.516250631155;2799.8320100794017;3303.8182899940734;3328.1679580104974;3610.151614370621;3789.829457674411;697.5709938387956;699.3565919354105;699.8180473076935;702.8488874891923;716.3194964783477;439.6131404364139;551.8454832646865;559.8432438917109;568.5167607533646;579.4090028171325;8005.159458256916;8798.208161265517;8886.644534218689;9146.496942668084;9278.768030799149;620.8013435700533;636.7930422612669;647.7311915555086;648.4617767253169;660.3857522762555;3858.6141385861188;4456.75487848165;4525.162844873709;4531.954680453219;4552.294394368878;86990.56504717436;120822.39588802;121451;122710.38644806719;132293.33853330673;66499.66750166219;81043;82848;85186;92145.07854921499;58125;66369.6726065476;81993.59003205065;85863.57068214745;86302.56848715716;9345.532723363669;9602.66390676323;9756;9765.511724413893;9808.773903262092;53517;53634.463655362946;54830.177547336025;57136.71431642815;57812.55468613318;75482.81635837034;79203.60398197972;79418;81100;81127;11901;11978.341191234565;12152.453139608915;12202;12606.810897836724;13579.660508487366;13643.727125457237;13844.515441959484;13944.233067181402;14042.227677477837;12781.552645657357;12873.67815804575;12901.741965160832;12909.289989050692;13025.957923366297;10739.785204296026;12677.556285529961;12732.299723515296;12881.612040599335;12971.221726696429;12478.688032799253;12578.937105314415;12626.55807046749;12667.746645066863;12775.744485110432;10714.410707410856;11132.443377831238;11297.943510282395;11436.94281528587;11552.711182220512;10813.891861081604;11215.943920280346;11308.264962777394;11378.601748938747;11393.259438136494 12:35:30 INFO - PROCESS | 1634 | __end_tp_report 12:35:30 INFO - PROCESS | 1634 | __start_cc_report 12:35:30 INFO - PROCESS | 1634 | _x_x_mozilla_cycle_collect,369 12:35:30 INFO - PROCESS | 1634 | __end_cc_report 12:35:30 INFO - PROCESS | 1634 | __startTimestamp1484685330565__endTimestamp 12:35:30 INFO - PROCESS | 1634 | 12:35:30 INFO - PROCESS | 1634 | ------- Summary: start ------- 12:35:30 INFO - PROCESS | 1634 | Number of tests: 6 12:35:30 INFO - PROCESS | 1634 | 12:35:30 INFO - PROCESS | 1634 | [#0] dojo.html Cycles:165 Average:5678.44 Median:5989.97 stddev:690.60 (11.5%) stddev-sans-first:652.94 12:35:30 INFO - PROCESS | 1634 | Values: 2733.7 2881.7 3397.1 3425.8 3438.6 5087.2 5224.6 5249.6 5654.0 5717.1 5673.5 5728.2 5735.4 5752.2 5799.9 4464.8 5134.6 5205.3 5235.2 5238.1 5229.3 5255.5 5261.0 5275.0 5289.0 4283.4 5062.1 5128.4 5243.3 5255.0 5156.7 5196.3 5196.3 5201.4 5213.1 4267.3 5095.0 5133.2 5215.3 5258.9 4728.0 5711.8 5737.2 5748.7 5795.9 6030.5 6089.0 6217.3 6242.2 6243.7 6120.9 6131.6 6188.1 6188.2 6201.5 5135.7 5491.0 5753.9 5774.6 5846.7 5805.7 6232.5 6248.2 6297.1 6304.6 6034.5 6050.1 6069.4 6132.4 6152.3 6052.6 6124.0 6129.4 6130.9 6133.8 6143.5 6148.6 6154.6 6157.5 6174.4 6071.6 6083.5 6110.4 6136.4 6212.2 4863.5 4909.6 4922.6 4924.1 4942.5 4370.4 4385.5 4410.3 4423.0 4461.9 5604.2 5824.7 5832.9 5856.3 5875.9 6092.4 6134.9 6140.8 6170.2 6228.9 5745.3 5840.5 5848.9 5855.4 5877.9 5296.3 5348.8 5356.9 5395.1 5423.0 6318.7 6352.4 6357.3 6374.1 6389.0 6125.8 6177.6 6180.2 6234.7 6239.0 5060.8 6313.5 6354.4 6369.7 6404.9 4582.3 6118.7 6203.3 6213.9 6243.2 5952.4 6100.7 6140.7 6222.6 6256.9 6102.9 6129.5 6133.3 6165.2 6183.5 6085.4 6113.4 6122.9 6170.4 6179.1 5757.7 5860.6 6005.9 6011.7 6037.4 6047.2 6080.5 6082.6 6124.1 6181.2 5950.0 5990.0 6016.9 6020.8 6081.7 12:35:30 INFO - PROCESS | 1634 | 12:35:30 INFO - PROCESS | 1634 | [#1] ext.html Cycles:180 Average:71032.10 Median:21690.67 stddev:157405.91 (725.7%) stddev-sans-first:157758.33 12:35:30 INFO - PROCESS | 1634 | Values: 476.2 484.9 491.0 503.9 543.6 36020.0 39769.4 39852.6 41934.8 42032.8 30467.8 40538.2 40624.4 41123.0 41213.4 9197.0 9317.1 9355.3 9358.6 9372.3 9246.2 9309.6 9324.2 9361.6 9367.9 9333.5 9336.8 9358.3 9358.5 9370.9 9280.1 9340.4 9348.7 9351.8 9375.2 9264.1 9338.2 9344.2 9363.2 9367.2 38856.6 38981.0 39365.0 39422.2 39743.4 3315.3 3427.0 3446.6 3959.1 4145.2 29140.9 29418.6 29434.7 30031.0 30901.2 3024.6 3184.4 3186.1 3219.9 3243.5 502649.0 505715.0 506160.0 507069.5 508982.0 60499.7 61016.8 61240.1 61592.7 61725.5 62855.7 62928.7 63088.7 63142.7 63228.7 16224.2 16250.9 16513.2 16526.6 16551.4 12606.5 12668.6 12672.2 12692.8 12695.9 17935.2 18570.1 18650.0 18666.6 18706.3 3233.8 6838.9 7262.2 7870.9 8036.1 881.0 921.7 926.2 946.4 1050.9 26382.9 27457.6 30274.5 30345.4 30544.2 1028.0 1035.0 1092.3 1093.5 1107.2 15152.7 15349.0 15568.5 15577.9 15816.9 692683.0 694526.0 700347.0 701599.0 704018.0 57070.0 57211.4 57890.7 57957.7 58046.8 1332.4 1346.9 1365.2 1374.4 1379.5 25662.0 29229.4 29839.7 30289.4 30420.2 49062.0 49228.3 49332.8 49431.0 49446.5 540195.0 540877.0 541650.0 541777.0 542352.0 21958.0 25829.7 26114.9 26560.2 26615.1 46124.0 46213.5 46452.1 46843.0 47160.8 38571.4 38616.8 38627.8 38815.8 39119.4 37603.4 37918.1 38646.6 39246.0 39396.0 36864.6 37215.4 37620.8 37659.4 37996.0 32783.3 34244.8 34430.1 34697.5 35056.5 25535.2 25771.6 25960.6 25991.8 26095.0 12:35:30 INFO - PROCESS | 1634 | 12:35:30 INFO - PROCESS | 1634 | [#2] jquery.html Cycles:180 Average:34376.72 Median:1512.50 stddev:99677.19 (6590.2%) stddev-sans-first:99924.03 12:35:30 INFO - PROCESS | 1634 | Values: 332.9 377.5 379.1 387.3 401.7 18945.6 19419.4 19767.9 20110.0 20328.0 11535.1 11548.3 11980.8 11992.2 12040.2 5316.5 5530.6 5572.8 5576.5 5601.4 3962.3 4175.2 4790.9 5433.9 5495.0 4187.9 6715.0 6769.0 6788.0 6797.8 6482.2 6657.5 6701.1 6710.5 6762.6 5907.7 5936.9 5943.7 5958.3 5967.8 13089.4 15204.6 23596.0 24688.9 24802.5 2142.6 2245.4 2310.1 2366.7 2692.1 10166.5 10241.2 10461.9 10494.0 14362.7 1834.2 1840.7 1869.2 1878.8 1894.9 76587.2 115681.1 119578.0 124785.0 137748.0 11479.6 11706.4 11807.6 11823.1 11966.6 12339.2 14952.2 14997.9 15008.3 15025.3 8407.9 8506.7 8576.1 8612.3 8710.5 8384.4 8450.9 8457.8 8543.2 8587.9 3792.4 3796.4 4561.2 4615.6 4781.1 1922.7 1990.7 2012.4 2022.1 2034.4 362.5 404.3 689.9 830.9 913.7 11270.5 11756.6 11811.8 12019.6 12089.0 428.0 428.5 432.2 726.3 777.1 7633.5 9183.2 9924.7 10452.3 10551.5 519741.0 583802.0 612047.0 623339.0 627513.0 122691.2 129287.4 137456.0 138165.0 145329.3 927.9 993.4 1003.4 1006.9 1009.9 15157.4 18280.6 18969.3 19036.0 19114.4 57210.4 57389.9 59446.0 59804.0 60110.0 54065.0 81535.0 82471.6 82876.8 91589.5 7233.4 7445.3 7551.2 7943.6 8258.1 9044.8 12250.7 13142.9 13151.4 13185.4 4317.6 5323.9 10276.4 10891.2 11136.4 10531.0 11034.1 11164.7 11401.9 11585.2 11308.2 11411.2 11523.7 11659.7 11739.5 7299.0 7393.9 7583.2 7673.2 7732.0 10742.8 11047.9 11299.5 11353.8 11396.9 12:35:30 INFO - PROCESS | 1634 | 12:35:30 INFO - PROCESS | 1634 | [#3] mootools.html Cycles:180 Average:5435.77 Median:4953.14 stddev:2971.29 (60.0%) stddev-sans-first:2964.41 12:35:30 INFO - PROCESS | 1634 | Values: 1434.9 1476.3 1477.0 1636.2 1691.9 7489.9 7763.3 8004.0 8490.1 8523.1 8010.6 8336.0 8349.1 8431.6 8484.3 2303.7 2324.7 2344.0 2346.3 2354.2 2350.3 2369.5 2392.6 2398.1 2399.9 2334.8 2341.4 2355.7 2360.3 2361.6 2230.4 2234.3 2250.2 2252.4 2254.3 2353.7 2369.1 2370.3 2379.4 2381.1 8407.0 8419.4 8468.2 8523.7 8526.3 4349.9 4466.9 4947.9 5121.1 5201.0 3436.7 3459.6 3548.3 3607.7 3611.6 1002.9 1026.3 1082.9 1083.6 1101.6 9209.8 9824.5 10102.6 10360.9 10969.5 4055.4 4504.5 4534.1 4693.5 4721.7 9214.2 9228.0 9258.0 9263.3 9273.0 5161.0 5162.9 5166.1 5173.4 5192.1 4264.5 4412.9 4637.6 4746.4 4750.7 3824.7 3842.4 3865.0 3927.8 3943.1 1285.3 1296.0 1296.1 1306.4 1312.5 899.5 915.7 925.7 927.4 940.2 5687.8 5781.2 5810.7 6030.6 6043.4 450.5 452.9 454.0 457.8 464.5 3904.4 3908.4 3922.5 3984.0 4056.4 9900.3 10114.3 10258.2 10286.6 10312.0 10081.9 10112.3 10161.8 10188.3 10235.5 7576.0 7808.9 7955.5 7963.2 8058.8 5792.6 5840.8 5866.1 5962.6 5983.1 9346.4 9374.5 9411.8 9532.4 9557.1 8987.4 9374.9 9443.2 9676.7 9754.8 7241.9 7243.2 7250.1 7290.2 7333.9 7827.0 7884.9 7899.8 7914.6 7922.2 6915.8 7010.6 7101.2 7136.9 7235.5 6021.2 6874.4 7022.5 7165.0 7207.4 5258.4 6483.8 6710.6 6797.7 6823.5 4917.9 4934.0 4938.6 4958.4 4988.9 4285.3 4763.1 4935.6 4995.1 5031.5 12:35:30 INFO - PROCESS | 1634 | 12:35:30 INFO - PROCESS | 1634 | [#4] prototype.html Cycles:180 Average:4486.45 Median:4925.47 stddev:1367.57 (27.8%) stddev-sans-first:1334.86 12:35:30 INFO - PROCESS | 1634 | Values: 302.3 306.5 312.7 315.9 316.8 4493.5 4543.8 5057.9 5261.6 5558.1 4326.4 5192.5 5229.6 5350.9 5449.3 4149.5 4154.9 4168.5 4169.0 4183.9 4005.4 4149.3 4153.0 4162.5 4205.0 3214.3 3981.4 4083.7 4099.3 4124.3 4070.7 4085.2 4117.4 4134.6 4137.2 2943.3 3556.6 3608.0 3627.3 3634.6 4551.5 4580.1 4990.7 5020.3 5295.8 3955.7 4096.7 4677.9 4696.4 4725.8 4770.1 5587.2 5668.3 5719.6 5764.4 3781.6 3791.8 3910.3 3931.2 3955.8 5947.8 6017.9 6018.8 6020.5 6022.4 4035.5 4035.6 4052.0 4057.1 4058.8 3791.6 3823.9 3824.5 4008.8 4112.7 4112.5 4404.0 4417.9 4678.9 4693.2 5689.8 5741.9 5784.2 5790.9 5794.4 1092.7 1169.6 1200.1 1263.0 1309.3 886.0 892.5 894.2 907.4 908.0 4463.6 5129.6 5174.4 5180.6 5194.5 4389.8 4889.2 5069.0 5368.6 5618.7 4499.9 4551.8 4795.7 5084.9 5141.6 2576.6 2894.5 2924.2 2939.2 2955.8 4758.4 5060.5 5251.8 5420.3 5688.5 4782.4 4961.7 5707.7 5773.5 5774.9 4662.0 5115.4 5197.7 5485.8 5781.9 4101.4 5291.0 5411.6 5451.9 5581.6 4500.9 4518.8 4605.7 4679.4 5598.3 5173.8 5240.9 5309.4 5363.8 5710.2 2981.7 3970.0 3972.5 3973.8 3991.4 5930.8 5931.6 5942.3 5965.5 5980.6 5398.7 5447.6 5729.7 5737.6 5794.5 4823.5 5549.5 5691.0 5694.6 5748.3 5051.4 5052.1 5390.6 5503.7 5616.6 5674.2 5760.8 5764.4 5770.9 5773.0 5085.6 5360.5 5602.6 5611.9 5618.6 12:35:30 INFO - PROCESS | 1634 | 12:35:30 INFO - PROCESS | 1634 | [#5] yui.html Cycles:180 Average:20161.30 Median:2448.18 stddev:29873.42 (1220.2%) stddev-sans-first:29921.11 12:35:30 INFO - PROCESS | 1634 | Values: 597.3 702.6 753.9 759.3 808.2 13828.4 14420.5 14795.0 14833.3 14974.3 13224.3 13377.5 13464.5 13486.9 13542.0 669.8 680.0 840.3 844.8 851.6 831.8 833.2 837.0 839.8 842.5 710.4 835.8 884.0 885.0 886.9 822.9 855.4 858.8 876.7 880.6 632.5 635.2 647.2 654.5 656.3 12386.9 12483.9 12498.4 12505.4 13701.7 10796.5 13082.4 13168.1 13204.5 13269.3 10640.0 10973.5 11244.6 11326.5 11367.1 3268.1 3523.5 3597.6 3751.4 3772.6 89683.0 89703.0 89967.8 90090.5 90785.0 10009.4 10212.2 10325.1 10359.9 10418.1 16342.7 16470.3 17422.4 19922.2 19951.7 6465.5 7403.2 7419.6 7503.7 7591.7 4107.3 4126.1 4177.5 4198.6 4206.5 2799.8 3303.8 3328.2 3610.2 3789.8 697.6 699.4 699.8 702.8 716.3 439.6 551.8 559.8 568.5 579.4 8005.2 8798.2 8886.6 9146.5 9278.8 620.8 636.8 647.7 648.5 660.4 3858.6 4456.8 4525.2 4532.0 4552.3 86990.6 120822.4 121451.0 122710.4 132293.3 66499.7 81043.0 82848.0 85186.0 92145.1 58125.0 66369.7 81993.6 85863.6 86302.6 9345.5 9602.7 9756.0 9765.5 9808.8 53517.0 53634.5 54830.2 57136.7 57812.6 75482.8 79203.6 79418.0 81100.0 81127.0 11901.0 11978.3 12152.5 12202.0 12606.8 13579.7 13643.7 13844.5 13944.2 14042.2 12781.6 12873.7 12901.7 12909.3 13026.0 10739.8 12677.6 12732.3 12881.6 12971.2 12478.7 12578.9 12626.6 12667.7 12775.7 10714.4 11132.4 11297.9 11436.9 11552.7 10813.9 11215.9 11308.3 11378.6 11393.3 12:35:30 INFO - PROCESS | 1634 | -------- Summary: end -------- 12:35:30 INFO - PROCESS | 1634 | 12:35:31 INFO - TEST-INFO | 1634: exit 0 12:35:31 INFO - TEST-OK | dromaeo_css | took 1115687ms 12:35:31 INFO - TEST-START | kraken 12:35:31 INFO - operating with platform_type : mac_ 12:35:31 INFO - Initialising browser for kraken test... 12:35:31 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/tmpY5nu2p/profile http://localhost:49242/getInfo.html) 12:35:34 INFO - PROCESS | 1646 | 2017-01-17 12:35:34.462 plugin-container[1647:10407] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8537, name = 'com.apple.tsm.portname' 12:35:34 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:35:34 INFO - PROCESS | 1646 | 2017-01-17 12:35:34.465 plugin-container[1647:10407] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c13, name = 'com.apple.CFPasteboardClient' 12:35:34 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:35:34 INFO - PROCESS | 1646 | 2017-01-17 12:35:34.465 plugin-container[1647:10407] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:35:34 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 12:35:34 INFO - PROCESS | 1646 | colorDepth:24 12:35:34 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 12:35:34 INFO - PROCESS | 1646 | __metrics 12:35:34 ERROR - PROCESS | 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 12:35:35 INFO - TEST-INFO | 1646: exit 0 12:35:35 INFO - Browser initialized. 12:35:35 INFO - Running cycle 1/1 for kraken test... 12:35:35 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/tmpY5nu2p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:35:38 INFO - PROCESS | 1648 | 2017-01-17 12:35:38.366 plugin-container[1649:10532] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 12:35:38 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:35:38 INFO - PROCESS | 1648 | 2017-01-17 12:35:38.367 plugin-container[1649:10532] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 12:35:38 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:35:38 INFO - PROCESS | 1648 | 2017-01-17 12:35:38.367 plugin-container[1649:10532] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:35:47 INFO - PROCESS | 1648 | RSS: Main: 229056512 12:35:47 INFO - PROCESS | 1648 | 12:35:50 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 12:35:51 INFO - PROCESS | 1648 | RSS: Main: 241106944 12:35:51 INFO - PROCESS | 1648 | 12:35:54 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 12:35:54 INFO - PROCESS | 1648 | RSS: Main: 249225216 12:35:54 INFO - PROCESS | 1648 | 12:35:58 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 12:35:59 INFO - PROCESS | 1648 | RSS: Main: 251187200 12:35:59 INFO - PROCESS | 1648 | 12:36:01 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 12:36:01 INFO - PROCESS | 1648 | RSS: Main: 253784064 12:36:01 INFO - PROCESS | 1648 | 12:36:03 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:36:04 INFO - PROCESS | 1648 | RSS: Main: 254119936 12:36:04 INFO - PROCESS | 1648 | 12:36:07 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 12:36:07 INFO - PROCESS | 1648 | RSS: Main: 258088960 12:36:07 INFO - PROCESS | 1648 | 12:36:10 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 12:36:11 INFO - PROCESS | 1648 | RSS: Main: 257974272 12:36:11 INFO - PROCESS | 1648 | 12:36:14 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 12:36:14 INFO - PROCESS | 1648 | RSS: Main: 258293760 12:36:14 INFO - PROCESS | 1648 | 12:36:16 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:36:17 INFO - PROCESS | 1648 | RSS: Main: 256974848 12:36:17 INFO - PROCESS | 1648 | 12:36:19 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:36:19 INFO - PROCESS | 1648 | RSS: Main: 256987136 12:36:19 INFO - PROCESS | 1648 | 12:36:22 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:36:22 INFO - PROCESS | 1648 | RSS: Main: 256987136 12:36:22 INFO - PROCESS | 1648 | 12:36:25 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:36:26 INFO - PROCESS | 1648 | RSS: Main: 257200128 12:36:26 INFO - PROCESS | 1648 | 12:36:29 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:36:29 INFO - PROCESS | 1648 | RSS: Main: 257277952 12:36:29 INFO - PROCESS | 1648 | 12:36:31 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 12:36:31 INFO - PROCESS | 1648 | RSS: Main: 259272704 12:36:31 INFO - PROCESS | 1648 | 12:36:31 INFO - PROCESS | 1648 | __start_tp_report 12:36:31 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 12:36:31 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 12:36:31 INFO - PROCESS | 1648 | |i|pagename|runs| 12:36:31 INFO - PROCESS | 1648 | |0;ai-astar;115;120;119;118;121;120;119;127;158;179 12:36:31 INFO - PROCESS | 1648 | |1;audio-beat-detection;141;113;111;143;112;114;110;99;173;96 12:36:31 INFO - PROCESS | 1648 | |2;audio-dft;258;268;198;279;194;198;193;202;199;199 12:36:31 INFO - PROCESS | 1648 | |3;audio-fft;81;57;59;82;76;85;71;59;81;60 12:36:31 INFO - PROCESS | 1648 | |4;audio-oscillator;85;71;89;72;70;73;85;70;70;88 12:36:31 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;114;87;87;86;90;77;137;133;89;85 12:36:31 INFO - PROCESS | 1648 | |6;imaging-darkroom;98;94;95;96;96;95;95;158;156;104 12:36:31 INFO - PROCESS | 1648 | |7;imaging-desaturate;89;90;85;88;90;90;90;91;88;110 12:36:31 INFO - PROCESS | 1648 | |8;json-parse-financial;57;56;57;65;75;93;93;60;61;60 12:36:31 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;68;75;70;86;90;71;94;111;109;109 12:36:31 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;86;87;86;86;84;92;87;74;98;109 12:36:31 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;150;160;143;137;160;161;145;161;206;158 12:36:31 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;165;172;166;167;166;167;166;170;164;167 12:36:31 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;69;70;69;68;69;67;69;70;66;70 12:36:31 INFO - PROCESS | 1648 | __end_tp_report 12:36:31 INFO - PROCESS | 1648 | __start_cc_report 12:36:31 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,965 12:36:31 INFO - PROCESS | 1648 | __end_cc_report 12:36:31 INFO - PROCESS | 1648 | __startTimestamp1484685391797__endTimestamp 12:36:31 INFO - PROCESS | 1648 | 12:36:31 INFO - PROCESS | 1648 | ------- Summary: start ------- 12:36:31 INFO - PROCESS | 1648 | Number of tests: 14 12:36:31 INFO - PROCESS | 1648 | 12:36:31 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:129.60 Median:120.50 stddev:21.30 (17.7%) stddev-sans-first:21.93 12:36:31 INFO - PROCESS | 1648 | Values: 115.0 120.0 119.0 118.0 121.0 120.0 119.0 127.0 158.0 179.0 12:36:31 INFO - PROCESS | 1648 | 12:36:31 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:121.20 Median:142.00 stddev:23.83 (16.8%) stddev-sans-first:24.18 12:36:31 INFO - PROCESS | 1648 | Values: 141.0 113.0 111.0 143.0 112.0 114.0 110.0 99.0 173.0 96.0 12:36:31 INFO - PROCESS | 1648 | 12:36:31 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:218.80 Median:200.50 stddev:34.63 (17.3%) stddev-sans-first:33.70 12:36:31 INFO - PROCESS | 1648 | Values: 258.0 268.0 198.0 279.0 194.0 198.0 193.0 202.0 199.0 199.0 12:36:31 INFO - PROCESS | 1648 | 12:36:31 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:71.10 Median:78.50 stddev:11.29 (14.4%) stddev-sans-first:11.39 12:36:31 INFO - PROCESS | 1648 | Values: 81.0 57.0 59.0 82.0 76.0 85.0 71.0 59.0 81.0 60.0 12:36:31 INFO - PROCESS | 1648 | 12:36:31 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:79.00 stddev:8.27 (10.5%) stddev-sans-first:8.29 12:36:31 INFO - PROCESS | 1648 | Values: 85.0 71.0 89.0 72.0 70.0 73.0 85.0 70.0 70.0 88.0 12:36:31 INFO - PROCESS | 1648 | 12:36:31 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:98.50 Median:86.50 stddev:21.45 (24.8%) stddev-sans-first:22.00 12:36:31 INFO - PROCESS | 1648 | Values: 114.0 87.0 87.0 86.0 90.0 77.0 137.0 133.0 89.0 85.0 12:36:31 INFO - PROCESS | 1648 | 12:36:31 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:108.70 Median:95.00 stddev:25.62 (27.0%) stddev-sans-first:26.88 12:36:31 INFO - PROCESS | 1648 | Values: 98.0 94.0 95.0 96.0 96.0 95.0 95.0 158.0 156.0 104.0 12:36:31 INFO - PROCESS | 1648 | 12:36:31 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:91.10 Median:90.00 stddev:6.85 (7.6%) stddev-sans-first:7.23 12:36:31 INFO - PROCESS | 1648 | Values: 89.0 90.0 85.0 88.0 90.0 90.0 90.0 91.0 88.0 110.0 12:36:31 INFO - PROCESS | 1648 | 12:36:31 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:67.70 Median:63.00 stddev:14.41 (22.9%) stddev-sans-first:14.76 12:36:31 INFO - PROCESS | 1648 | Values: 57.0 56.0 57.0 65.0 75.0 93.0 93.0 60.0 61.0 60.0 12:36:31 INFO - PROCESS | 1648 | 12:36:31 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:88.30 Median:73.00 stddev:17.11 (23.4%) stddev-sans-first:16.50 12:36:31 INFO - PROCESS | 1648 | Values: 68.0 75.0 70.0 86.0 90.0 71.0 94.0 111.0 109.0 109.0 12:36:31 INFO - PROCESS | 1648 | 12:36:31 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:86.50 stddev:9.28 (10.7%) stddev-sans-first:9.78 12:36:31 INFO - PROCESS | 1648 | Values: 86.0 87.0 86.0 86.0 84.0 92.0 87.0 74.0 98.0 109.0 12:36:31 INFO - PROCESS | 1648 | 12:36:31 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:158.10 Median:160.00 stddev:18.94 (11.8%) stddev-sans-first:19.86 12:36:31 INFO - PROCESS | 1648 | Values: 150.0 160.0 143.0 137.0 160.0 161.0 145.0 161.0 206.0 158.0 12:36:31 INFO - PROCESS | 1648 | 12:36:31 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:167.00 stddev:2.36 (1.4%) stddev-sans-first:2.39 12:36:31 INFO - PROCESS | 1648 | Values: 165.0 172.0 166.0 167.0 166.0 167.0 166.0 170.0 164.0 167.0 12:36:31 INFO - PROCESS | 1648 | 12:36:31 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.00 stddev:1.34 (1.9%) stddev-sans-first:1.41 12:36:31 INFO - PROCESS | 1648 | Values: 69.0 70.0 69.0 68.0 69.0 67.0 69.0 70.0 66.0 70.0 12:36:31 INFO - PROCESS | 1648 | -------- Summary: end -------- 12:36:31 INFO - PROCESS | 1648 | 12:36:32 INFO - TEST-INFO | 1648: exit 0 12:36:32 INFO - TEST-OK | kraken | took 61554ms 12:36:32 INFO - SUITE-END | took 1177s 12:36:32 INFO - Completed test suite (00:19:37) 12:36:32 INFO - javascript benchmark 12:36:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7070.216457856797, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2733.6719593648763, 2881.7176356521345, 3397.065806903102, 3425.777324473909, 3438.6045604755445, 5087.236914462833, 5224.63427560071, 5249.632525723201, 5654.0, 5717.142428635701, 5673.546116310696, 5728.198052272685, 5735.397783232753, 5752.165935939286, 5799.9130013049735, 4464.776761161929, 5134.563562097226, 5205.3233079699585, 5235.214717792323, 5238.083335416306, 5229.346331708536, 5255.527002569768, 5261.0, 5274.973625131888, 5289.0, 4283.357496375537, 5062.063518249117, 5128.4358720540895, 5243.318368612075, 5254.973725131369, 5156.664816786915, 5196.32447781786, 5196.324477817897, 5201.40183878855, 5213.061648903196, 4267.338562522814, 5095.006473737622, 5133.230015497668, 5215.269862219292, 5258.947410525922, 4727.952720472786, 5711.828645140611, 5737.196792449061, 5748.712564371806, 5795.8551036224435, 6030.487408570233, 6089.0, 6217.2539295284405, 6242.219722534684, 6243.687815609202, 6120.904292401164, 6131.59076170999, 6188.10272510488, 6188.226471691039, 6201.472874805647, 5135.666181698178, 5490.956718223565, 5753.856153596152, 5774.566907481872, 5846.7076646167525, 5805.651660900275, 6232.532560058014, 6248.156498872685, 6297.149884765498, 6304.5586808922935, 6034.5172386209, 6050.092486127028, 6069.423404776539, 6132.386761323832, 6152.261728592597, 6052.57631965767, 6124.014786914997, 6129.419919118862, 6130.856783744145, 6133.82435864808, 6143.481172719164, 6148.631082135086, 6154.599951003171, 6157.538184636171, 6174.38256174379, 6071.574989750763, 6083.540095152081, 6110.406879924458, 6136.355682653347, 6212.223472065992, 4863.465018847924, 4909.631777616693, 4922.606191504743, 4924.113659541317, 4942.481039490874, 4370.431843860278, 4385.473743150842, 4410.25025745624, 4423.049044455393, 4461.866144015685, 5604.159376093618, 5824.708764561755, 5832.88334233313, 5856.326522449973, 5875.882482350329, 6092.3603021682975, 6134.926457743726, 6140.754369825247, 6170.167027451236, 6228.937710622927, 5745.2818397700285, 5840.474357307863, 5848.853778655568, 5855.443732845373, 5877.9118313225335, 5296.311479507639, 5348.849997250532, 5356.946430535645, 5395.136778115482, 5422.972885135628, 6318.71565779541, 6352.428281454599, 6357.332480089616, 6374.107624932421, 6388.977763557808, 6125.784909797561, 6177.62934223948, 6180.196574445294, 6234.719437625227, 6239.032949892774, 5060.84817455477, 6313.463355614824, 6354.4281014708895, 6369.7452101915405, 6404.871902561829, 4582.33556134352, 6118.684482836212, 6203.286622038523, 6213.875722485615, 6243.157173781481, 5952.375000625046, 6100.725467354068, 6140.657627863787, 6222.595531290452, 6256.937430625636, 6102.938970610237, 6129.509639228941, 6133.325334213144, 6165.167702360124, 6183.505319574513, 6085.361037091218, 6113.388661133851, 6122.877542449037, 6170.352113028068, 6179.1040299155475, 5757.683327417032, 5860.648361098348, 6005.90991135142, 6011.729472173675, 6037.396260373927, 6047.183630209865, 6080.452759251689, 6082.635041897501, 6124.050772130327, 6181.227346581677, 5949.970250148722, 5989.970050149721, 6016.939830601638, 6020.789272375446, 6081.665508397081], "value": 5632.751736899859, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.24039656747476, 484.85212010336846, 491.0424671889811, 503.85373275797565, 543.6466296907013, 36020.0, 39769.40345894814, 39852.601473985254, 41934.79032604833, 42032.789836050775, 30467.84766076172, 40538.18923621533, 40624.39063414051, 41123.0, 41213.38179927296, 9196.98833128357, 9317.068293170696, 9355.298352623546, 9358.625654973794, 9372.343935924488, 9246.214071803905, 9309.627614895397, 9324.160825525702, 9361.625534978593, 9367.859482107773, 9333.486658233795, 9336.81326373472, 9358.29812764042, 9358.532073396336, 9370.859437108415, 9280.118388753057, 9340.392874463138, 9348.719538413858, 9351.812963740687, 9375.15623593873, 9264.073592640681, 9338.159565639056, 9344.15902568772, 9363.204127649158, 9367.203787677987, 38856.611433885584, 38981.02547436309, 39365.01587460308, 39422.21155576872, 39743.403848942, 3315.287213249163, 3426.9657303426898, 3446.638102999199, 3959.1289916218384, 4145.191687620915, 29140.854295728597, 29418.558721619193, 29434.705652943412, 30030.98127221821, 30901.227469313224, 3024.5916801231774, 3184.4268031754277, 3186.123815950609, 3219.8873039443506, 3243.4810430331268, 502649.0, 505715.0, 506160.0, 507069.46465267806, 508982.0, 60499.69750151221, 61016.779664406466, 61240.08139877905, 61592.69203653953, 61725.45686357787, 62855.685721572016, 62928.685356572925, 63088.684556576925, 63142.684286578275, 63228.68385658042, 16224.188790560425, 16250.918745406198, 16513.174341283127, 16526.586835329213, 16551.420700275674, 12606.495740170474, 12668.556600518938, 12672.239665620093, 12692.80960785589, 12695.936520317524, 17935.197257231204, 18570.071496425124, 18650.031989763145, 18666.626667466575, 18706.345277915207, 3233.8221397823345, 6838.863222735517, 7262.164851042197, 7870.858725484887, 8036.116027236999, 880.9823803523893, 921.7373048681094, 926.1710768861831, 946.4179529589253, 1050.9438014795235, 26382.927992370114, 27457.588136177972, 30274.54588181179, 30345.393092138034, 30544.236394090327, 1027.9743006424899, 1034.9793004139874, 1092.2736380307008, 1093.5024563823442, 1107.2083460325744, 15152.696946061016, 15349.002314849722, 15568.532944011698, 15577.922110389376, 15816.92091539558, 692683.0, 694526.0, 700347.0, 701599.0, 704018.0, 57070.0, 57211.427885721445, 57890.710546447, 57957.71021144867, 58046.8390632185, 1332.4070788499218, 1346.912450690702, 1365.1877133105786, 1374.4364810427876, 1379.5102738527878, 25661.973521059695, 29229.392383419116, 29839.70160298456, 30289.394212116076, 30420.239494012825, 49062.0, 49228.2615760771, 49332.75333623453, 49431.0, 49446.50553494419, 540195.0, 540877.0, 541650.0, 541777.0, 542352.0, 21958.0, 25829.741702582734, 26114.86942565275, 26560.203193904214, 26615.06847260414, 46124.0, 46213.53786462092, 46452.07095857996, 46843.0, 47160.820979475786, 38571.421428679154, 38616.80691596524, 38627.80686096552, 38815.80592097021, 39119.41320880246, 37603.435948461345, 37918.052048699006, 38646.6135338643, 39246.01884952899, 39396.0, 36864.63135368612, 37215.441768372955, 37620.811895940344, 37659.43510847395, 37996.0, 32783.34433311273, 34244.82877585596, 34430.13924651904, 34697.45294461181, 35056.47415288722, 25535.23394298174, 25771.613425798252, 25960.610590840774, 25991.830367633123, 26094.956201751716], "value": 19733.884847638303, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.9450640644294, 377.52620461321055, 379.10342041072863, 387.2526024772192, 401.65859019833147, 18945.621087578256, 19419.41741747748, 19767.91276479793, 20109.99999999998, 20327.983600819975, 11535.077193824494, 11548.307101573911, 11980.760384792298, 11992.220505667126, 12040.217385869912, 5316.548093412055, 5530.640508366963, 5572.832815015556, 5576.470235327637, 5601.3558440779425, 3962.2669806085823, 4175.227582897159, 4790.898093438511, 5433.864153396177, 5495.0, 4187.895302617429, 6714.966425167891, 6769.0, 6788.049673045777, 6797.796066118024, 6482.189726284215, 6657.46740260778, 6701.128853249095, 6710.463162946952, 6762.628055456948, 5907.7046147692445, 5936.851578710523, 5943.732532036025, 5958.255218097738, 5967.7911273105665, 13089.41097650608, 15204.619884502865, 23596.0, 24688.876555617288, 24802.5039499209, 2142.582196471689, 2245.371296037112, 2310.122153581628, 2366.7396586375485, 2692.0597133363053, 10166.542505587266, 10241.180705543538, 10461.947690261575, 10494.003069708442, 14362.707356337874, 1834.2112891456668, 1840.6870831958465, 1869.2149297295168, 1878.821511956362, 1894.8578856585673, 76587.23412765856, 115681.10558584477, 119578.0, 124785.0, 137748.0, 11479.598214062467, 11706.414679266003, 11807.645770626896, 11823.113266505046, 11966.64100076999, 12339.197952133083, 14952.177630230442, 14997.925010374878, 15008.324625391884, 15025.323860426308, 8407.873881891777, 8506.74479765608, 8576.05663377028, 8612.267957223581, 8710.52092134926, 8384.413091083688, 8450.87323690145, 8457.788555286166, 8543.188397102265, 8587.871181932276, 3792.355299599075, 3796.430535419654, 4561.178987782231, 4615.607673347803, 4781.091592597395, 1922.7406049037902, 1990.741203643517, 2012.356046065245, 2022.1405902491676, 2034.3998520436508, 362.49383810268375, 404.3429427180831, 689.9103116594911, 830.9459885107449, 913.7487191022522, 11270.549178032787, 11756.58851940178, 11811.763764724657, 12019.63941081769, 12089.0, 428.0347815675694, 428.47939753199387, 432.20042920597274, 726.2918654312003, 777.0597576931863, 7633.465657403928, 9183.219426348835, 9924.70978772755, 10452.268341216193, 10551.472426378803, 519741.0, 583802.0, 612047.0, 623339.0, 627513.0, 122691.15963260559, 129287.35356323158, 137456.0, 138165.0, 145329.27335363466, 927.9025702301161, 993.4138858073627, 1003.3879333606494, 1006.8942761010135, 1009.878814542245, 15157.393704251706, 18280.634387312446, 18969.33607323737, 19036.0, 19114.426567203005, 57210.42789572051, 57389.85220295487, 59446.0, 59804.0, 60110.0, 54065.0, 81535.0, 82471.58764206141, 82876.75684864611, 91589.54205228931, 7233.421326293778, 7445.292697193758, 7551.207123252199, 7943.563104029334, 8258.050324212792, 9044.819103618023, 12250.693732656755, 13142.948564115039, 13151.408186631434, 13185.40665670028, 4317.568243175658, 5323.920141197807, 10276.434796086287, 10891.237613366988, 11136.38749868765, 10531.0, 11034.117270618492, 11164.665060048212, 11401.942990285328, 11585.246958947979, 11308.151888608223, 11411.201215914807, 11523.711907202387, 11659.708507287385, 11739.47172377228, 7298.96350518244, 7393.8521229574035, 7583.165851756192, 7673.232676732282, 7731.961340193263, 10742.785144297226, 11047.889521104686, 11299.491522881326, 11353.829692554453, 11396.886031139582], "value": 8909.245320156235, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1434.9426022959083, 1476.335648957969, 1476.9926150369247, 1636.1819090454774, 1691.890027148236, 7489.887651685229, 7763.301302882739, 8004.03951525817, 8490.066092729794, 8523.105073967237, 8010.5594192319395, 8336.0, 8349.081601023883, 8431.62057707403, 8484.278836298907, 2303.677485152076, 2324.651302304651, 2344.0272287000876, 2346.3078391874437, 2354.152505098163, 2350.27141586108, 2369.5379401016808, 2392.5932591459496, 2398.0647547456506, 2399.892004859777, 2334.8015418689433, 2341.426350544119, 2355.6584295277175, 2360.327306717586, 2361.633946738258, 2230.431240033781, 2234.2850287907877, 2250.246167533862, 2252.3580779477934, 2254.256095488493, 2353.658719485665, 2369.1115831563166, 2370.2533701883854, 2379.428937055094, 2381.1070848431837, 8407.0, 8419.410641255115, 8468.195521425454, 8523.65905363791, 8526.275266602284, 4349.869503914888, 4466.865994020184, 4947.901041979177, 5121.103806833827, 5201.037808005531, 3436.74224433166, 3459.636738142484, 3548.3258180945536, 3607.6753092221566, 3611.56661200655, 1002.8867956568204, 1026.2559644257929, 1082.86464191976, 1083.6044843632017, 1101.564881871656, 9209.815803683956, 9824.459654718987, 10102.646407375707, 10360.94819525905, 10969.451527423676, 4055.3916912463364, 4504.527024662429, 4534.13851368239, 4693.460252070987, 4721.716696998191, 9214.216791572791, 9227.953860230657, 9257.953710231406, 9263.305252105984, 9273.0, 5161.045206636784, 5162.922556161586, 5166.095933211673, 5173.379194496684, 5192.143296356135, 4264.530901600821, 4412.911741765147, 4637.605803506673, 4746.406699162605, 4750.691205071659, 3824.6749026333073, 3842.366009608439, 3864.9806750966063, 3927.7643341399607, 3943.132510847609, 1285.2673975833684, 1295.9870401296055, 1296.1121631682329, 1306.4121145484569, 1312.4618906248393, 899.5412339706796, 915.697819719491, 925.7407925780792, 927.4456682948141, 940.210223412337, 5687.755650434456, 5781.248437703071, 5810.6513609183585, 6030.6080104793045, 6043.365446628129, 450.54720006393364, 452.8958339581849, 454.03744062587526, 457.8420444946488, 464.4705036258668, 3904.355781296111, 3908.4332771747936, 3922.5096862892137, 3984.0, 4056.4320995060716, 9900.306978511579, 10114.29199955996, 10258.17934565233, 10286.588536458608, 10311.968803119627, 10081.949590252001, 10112.342697724624, 10161.847572286422, 10188.286819922683, 10235.539400726986, 7575.962120189364, 7808.921910780819, 7955.4828936118965, 7963.1638677939145, 8058.758237252891, 5792.565557583114, 5840.795572154954, 5866.149408335853, 5962.612430192024, 5983.132445795422, 9346.392484488486, 9374.484403357608, 9411.81176376455, 9532.428054316762, 9557.139857412867, 8987.370884038055, 9374.906250937676, 9443.244540436886, 9676.661316853877, 9754.75613109678, 7241.855162896607, 7243.167035790952, 7250.093738282715, 7290.234525374972, 7333.889991650236, 7827.0, 7884.881726773988, 7899.84200316002, 7914.604269786603, 7922.168172341821, 6915.757948471779, 7010.579365238102, 7101.218865924641, 7136.892946605909, 7235.457340699363, 6021.187139736079, 6874.381305682513, 7022.54353467023, 7165.0, 7207.351338379571, 5258.421573626822, 6483.805485835433, 6710.597364158166, 6797.72809087631, 6823.4882383822405, 4917.877053073702, 4934.037862616755, 4938.629602779734, 4958.380202474691, 4988.9251661225835, 4285.250081235834, 4763.095011947719, 4935.555799978019, 4995.100881841158, 5031.547160755403], "value": 4351.0775153267705, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.31978049388863, 306.54478100021464, 312.6966842163102, 315.89575440104767, 316.7529327124843, 4493.483249426317, 4543.795529201186, 5057.924131138036, 5261.605379596526, 5558.1107022876395, 4326.394304797331, 5192.454792246816, 5229.633925625208, 5350.892982140354, 5449.264349312842, 4149.46057012588, 4154.896127596804, 4168.541460439349, 4168.958310416887, 4183.874483765492, 4005.376478406634, 4149.2531344358, 4153.0065568527125, 4162.542120366757, 4204.957950420502, 3214.2607200343937, 3981.407437025184, 4083.6528895043957, 4099.282625540533, 4124.278251306025, 4070.653994410479, 4085.1608132347883, 4117.423560701504, 4134.586541345841, 4137.234611596864, 2943.2936095337177, 3556.590992035923, 3608.0414060470425, 3627.3470775260444, 3634.56385233771, 4551.469167067238, 4580.106879158566, 4990.72736452206, 5020.297158397827, 5295.814646487393, 3955.7428767130045, 4096.692748043879, 4677.906441871178, 4696.3894693689945, 4725.786465203694, 4770.117528257283, 5587.161925711135, 5668.263125793662, 5719.59962802604, 5764.394738552433, 3781.565120011179, 3791.8104094795426, 3910.3156947534353, 3931.174453364797, 3955.8417663293435, 5947.791827286068, 6017.939820601781, 6018.819435416901, 6020.488258498033, 6022.427869352405, 4035.4955630546183, 4035.636792688672, 4051.959480405217, 4057.0668746188544, 4058.837646494166, 3791.6208379161862, 3823.9426408603895, 3824.464574959508, 4008.8396464141692, 4112.732672376345, 4112.485939257593, 4404.0, 4417.911641767147, 4678.906421871544, 4693.151615675114, 5689.829305120853, 5741.88516229673, 5784.1902133701315, 5790.888469186924, 5794.449527294901, 1092.6831218946575, 1169.5789515774316, 1200.099925056208, 1262.9684257893625, 1309.2602679486251, 885.9778505537284, 892.4511425473416, 894.2488309819782, 907.4101833808078, 907.9636814527345, 4463.575960283768, 5129.589632829365, 5174.404943431478, 5180.6169162185815, 5194.532492075731, 4389.846355377546, 4889.217725163957, 5069.020818657336, 5368.624196306299, 5618.679610291578, 4499.887502812456, 4551.769632879931, 4795.688280261772, 5084.949150508522, 5141.640085194075, 2576.587745960675, 2894.5079336512845, 2924.239697678619, 2939.1623387334507, 2955.807872488281, 4758.405934012076, 5060.507150390644, 5251.789928402821, 5420.306774647745, 5688.459596338341, 4782.397896704643, 4961.727105009187, 5707.74315155819, 5773.451522105393, 5774.88450230993, 4662.016309561883, 5115.411727651293, 5197.687029272013, 5485.835424937259, 5781.942180578309, 4101.425800387889, 5291.031726205346, 5411.567074633941, 5451.945480545144, 5581.6371935824045, 4500.887477813081, 4518.819247230074, 4605.723656580616, 4679.3916790816975, 5598.272224610774, 5173.81891633806, 5240.921386179134, 5309.415964244007, 5363.758630861698, 5710.200571919851, 2981.686922873067, 3969.9801500993467, 3972.503437070366, 3973.7615743055508, 3991.4212439196735, 5930.8220753375745, 5931.606072572856, 5942.286925568959, 5965.4929331005615, 5980.581359304807, 5398.7300634967305, 5447.564194864322, 5729.742161602821, 5737.569682273763, 5794.478496935128, 4823.517648235148, 5549.500544950813, 5691.0, 5694.572907031907, 5748.252727145445, 5051.449018245762, 5052.095045348866, 5390.568754499709, 5503.669779813224, 5616.634918730269, 5674.177244299471, 5760.782098891873, 5764.423557644202, 5770.884582308414, 5773.0, 5085.644004919768, 5360.517553420211, 5602.551795856403, 5611.859703507282, 5618.606697531133], "value": 4050.845149139368, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.3071237364658, 702.5960072958048, 753.9246075392462, 759.3090287838069, 808.2200676347326, 13828.377723002464, 14420.495282665122, 14795.038322509043, 14833.258337083156, 14974.32615532301, 13224.338783060832, 13377.464901403931, 13464.46142154315, 13486.865131348684, 13542.0, 669.8392385827403, 679.9932000679979, 840.315143158327, 844.7634662294568, 851.595492141231, 831.8045259364075, 833.204289903141, 837.0457678246773, 839.7816567692413, 842.4987132656059, 710.3926143147612, 835.7534527314457, 884.0496466298755, 884.9955750221272, 886.8625363068702, 822.880682301069, 855.4183155454289, 858.8368210040104, 876.7325965580495, 880.6301353431575, 632.512965016939, 635.2377147423065, 647.1522305779444, 654.4829584628138, 656.2879275985562, 12386.876131238752, 12483.937580312131, 12498.375081245993, 12505.3747312634, 13701.725965480635, 10796.460176991119, 13082.411291491906, 13168.123907069697, 13204.537841175608, 13269.270190139538, 10639.98920102589, 10973.451327433597, 11244.550217991271, 11326.490307936163, 11367.09063274936, 3268.117608245784, 3523.5243242162237, 3597.5503062117236, 3751.4185301278344, 3772.585015648304, 89683.0, 89703.0, 89967.75080622971, 90090.54954725184, 90785.00682462462, 10009.449480278654, 10212.234082443847, 10325.07074363296, 10359.948200259101, 10418.06237438619, 16342.673146537003, 16470.341186352653, 17422.390216342366, 19922.203111875362, 19951.700724489143, 6465.547411681231, 7403.22266162056, 7419.629018549051, 7503.737369192052, 7591.696332146652, 4107.260693075275, 4126.09225970292, 4177.4569305990635, 4198.6011328923705, 4206.516250631155, 2799.8320100794017, 3303.8182899940734, 3328.1679580104974, 3610.151614370621, 3789.829457674411, 697.5709938387956, 699.3565919354105, 699.8180473076935, 702.8488874891923, 716.3194964783477, 439.6131404364139, 551.8454832646865, 559.8432438917109, 568.5167607533646, 579.4090028171325, 8005.159458256916, 8798.208161265517, 8886.644534218689, 9146.496942668084, 9278.768030799149, 620.8013435700533, 636.7930422612669, 647.7311915555086, 648.4617767253169, 660.3857522762555, 3858.6141385861188, 4456.75487848165, 4525.162844873709, 4531.954680453219, 4552.294394368878, 86990.56504717436, 120822.39588802, 121451.0, 122710.38644806719, 132293.33853330673, 66499.66750166219, 81043.0, 82848.0, 85186.0, 92145.07854921499, 58125.0, 66369.6726065476, 81993.59003205065, 85863.57068214745, 86302.56848715716, 9345.532723363669, 9602.66390676323, 9756.0, 9765.511724413893, 9808.773903262092, 53517.0, 53634.463655362946, 54830.177547336025, 57136.71431642815, 57812.55468613318, 75482.81635837034, 79203.60398197972, 79418.0, 81100.0, 81127.0, 11901.0, 11978.341191234565, 12152.453139608915, 12202.0, 12606.810897836724, 13579.660508487366, 13643.727125457237, 13844.515441959484, 13944.233067181402, 14042.227677477837, 12781.552645657357, 12873.67815804575, 12901.741965160832, 12909.289989050692, 13025.957923366297, 10739.785204296026, 12677.556285529961, 12732.299723515296, 12881.612040599335, 12971.221726696429, 12478.688032799253, 12578.937105314415, 12626.55807046749, 12667.746645066863, 12775.744485110432, 10714.410707410856, 11132.443377831238, 11297.943510282395, 11436.94281528587, 11552.711182220512, 10813.891861081604, 11215.943920280346, 11308.264962777394, 11378.601748938747, 11393.259438136494], "value": 7155.379545177706, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1555.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 120.0, 119.0, 118.0, 121.0, 120.0, 119.0, 127.0, 158.0, 179.0], "value": 129.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 113.0, 111.0, 143.0, 112.0, 114.0, 110.0, 99.0, 173.0, 96.0], "value": 121.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [258.0, 268.0, 198.0, 279.0, 194.0, 198.0, 193.0, 202.0, 199.0, 199.0], "value": 218.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 57.0, 59.0, 82.0, 76.0, 85.0, 71.0, 59.0, 81.0, 60.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 89.0, 72.0, 70.0, 73.0, 85.0, 70.0, 70.0, 88.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 87.0, 87.0, 86.0, 90.0, 77.0, 137.0, 133.0, 89.0, 85.0], "value": 98.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 94.0, 95.0, 96.0, 96.0, 95.0, 95.0, 158.0, 156.0, 104.0], "value": 108.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 85.0, 88.0, 90.0, 90.0, 90.0, 91.0, 88.0, 110.0], "value": 91.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 65.0, 75.0, 93.0, 93.0, 60.0, 61.0, 60.0], "value": 67.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 70.0, 86.0, 90.0, 71.0, 94.0, 111.0, 109.0, 109.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 86.0, 86.0, 84.0, 92.0, 87.0, 74.0, 98.0, 109.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 160.0, 143.0, 137.0, 160.0, 161.0, 145.0, 161.0, 206.0, 158.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 172.0, 166.0, 167.0, 166.0, 167.0, 166.0, 170.0, 164.0, 167.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 69.0, 68.0, 69.0, 67.0, 69.0, 70.0, 66.0, 70.0], "value": 68.7, "unit": "score"}]}]} 12:36:33 INFO - Return code: 0 12:36:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:36:33 ERROR - # TBPL SUCCESS # 12:36:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:36:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:36:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:36:33 INFO - 2017-01-17 12:36:33.138 screenresolution[1653:10915] starting screenresolution argv=screenresolution get 12:36:33 INFO - 2017-01-17 12:36:33.141 screenresolution[1653:10915] Display 0: 1600x1200x32@60 12:36:33 INFO - 2017-01-17 12:36:33.148 screenresolution[1654:10918] starting screenresolution argv=screenresolution list 12:36:33 INFO - Available Modes on Display 0 12:36:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:36:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:36:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:36:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:36:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:36:33 INFO - Intel Iris: 12:36:33 INFO - Chipset Model: Intel Iris 12:36:33 INFO - Type: GPU 12:36:33 INFO - Bus: Built-In 12:36:33 INFO - VRAM (Dynamic, Max): 1536 MB 12:36:33 INFO - Vendor: Intel (0x8086) 12:36:33 INFO - Device ID: 0x0a2e 12:36:33 INFO - Revision ID: 0x0009 12:36:33 INFO - Displays: 12:36:33 INFO - Display: 12:36:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:36:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:36:33 INFO - Display Serial Number: 1600x1200 60 12:36:33 INFO - Main Display: Yes 12:36:33 INFO - Mirror: Off 12:36:33 INFO - Online: Yes 12:36:33 INFO - Rotation: Supported 12:36:33 INFO - Return code: 0 12:36:33 INFO - Running post-action listener: _resource_record_post_action 12:36:33 INFO - [mozharness: 2017-01-17 20:36:33.393056Z] Finished run-tests step (success) 12:36:33 INFO - Running post-run listener: _resource_record_post_run 12:36:33 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 39721472; Write bytes: 661753344; Read time: 389; Write time: 1761 12:36:33 INFO - TinderboxPrint: CPU usage
54.3% 12:36:33 INFO - TinderboxPrint: I/O read bytes / time
39,721,472 / 389 12:36:33 INFO - TinderboxPrint: I/O write bytes / time
661,753,344 / 1,761 12:36:33 INFO - TinderboxPrint: CPU idle
2,212.4 (45.7%) 12:36:33 INFO - TinderboxPrint: CPU system
976.4 (20.2%) 12:36:33 INFO - TinderboxPrint: CPU user
1,648.9 (34.1%) 12:36:33 INFO - TinderboxPrint: Swap in / out
388,583,424 / 0 12:36:33 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 341082624; Write bytes: 335026176; Read time: 26557; Write time: 585 12:36:33 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 38750720; Write bytes: 310052352; Read time: 374; Write time: 1124 12:36:33 INFO - Running post-run listener: _upload_blobber_files 12:36:33 INFO - Blob upload gear active. 12:36:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:36:33 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:36:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:36:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:36:34 INFO - (blobuploader) - INFO - Open directory for files ... 12:36:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:36:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:36:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:36:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:36:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:36:35 INFO - (blobuploader) - INFO - Done attempting. 12:36:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:36:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:36:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:36:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:36:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:36:36 INFO - (blobuploader) - INFO - Done attempting. 12:36:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:36:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:36:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:36:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:36:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:36:39 INFO - (blobuploader) - INFO - Done attempting. 12:36:39 INFO - (blobuploader) - INFO - Iteration through files over. 12:36:39 INFO - Return code: 0 12:36:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:36:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:36:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d310397dbb9257d702c908fdf3db045553249e95cddb7eecdb718dba6d4b77f5e222d707ad0d458ab20fb79dc32833b220cbd7a81a658c7b89e3d2f888ade665", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54b3ad78a18ae42438dc5f95dd81121c3d61f8b639eb5a46dbe062a9fe7ef3087d83c880a4aaf1921b5d063b2a324a4523fa3a8db733609f3136c6af73e78985", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a98c0b3314f38a9b67b875d6c2d20428dab5ee963aa4d5c128bf34bc0d2b7e2390486cd8c416e5a8488e6ef151abec99f810d071c089800ca41899c71e29e2e2"} 12:36:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:36:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:36:39 INFO - Contents: 12:36:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d310397dbb9257d702c908fdf3db045553249e95cddb7eecdb718dba6d4b77f5e222d707ad0d458ab20fb79dc32833b220cbd7a81a658c7b89e3d2f888ade665", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54b3ad78a18ae42438dc5f95dd81121c3d61f8b639eb5a46dbe062a9fe7ef3087d83c880a4aaf1921b5d063b2a324a4523fa3a8db733609f3136c6af73e78985", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a98c0b3314f38a9b67b875d6c2d20428dab5ee963aa4d5c128bf34bc0d2b7e2390486cd8c416e5a8488e6ef151abec99f810d071c089800ca41899c71e29e2e2"} 12:36:39 INFO - Running post-run listener: copy_logs_to_upload_dir 12:36:39 INFO - Copying logs to upload dir... 12:36:39 INFO - mkdir: /builds/slave/test/build/upload/logs 12:36:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.942466 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-17 12:36:39.718299) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-17 12:36:39.721798) ========= 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.TfVsuxYWqJ/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.CN1NYwHi9d/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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-Aurora/sha512/d310397dbb9257d702c908fdf3db045553249e95cddb7eecdb718dba6d4b77f5e222d707ad0d458ab20fb79dc32833b220cbd7a81a658c7b89e3d2f888ade665", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54b3ad78a18ae42438dc5f95dd81121c3d61f8b639eb5a46dbe062a9fe7ef3087d83c880a4aaf1921b5d063b2a324a4523fa3a8db733609f3136c6af73e78985", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a98c0b3314f38a9b67b875d6c2d20428dab5ee963aa4d5c128bf34bc0d2b7e2390486cd8c416e5a8488e6ef151abec99f810d071c089800ca41899c71e29e2e2"} build_url:https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011416 build_url: 'https://queue.taskcluster.net/v1/task/J34klqycSIqUCbiMbpRtoA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d310397dbb9257d702c908fdf3db045553249e95cddb7eecdb718dba6d4b77f5e222d707ad0d458ab20fb79dc32833b220cbd7a81a658c7b89e3d2f888ade665", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54b3ad78a18ae42438dc5f95dd81121c3d61f8b639eb5a46dbe062a9fe7ef3087d83c880a4aaf1921b5d063b2a324a4523fa3a8db733609f3136c6af73e78985", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a98c0b3314f38a9b67b875d6c2d20428dab5ee963aa4d5c128bf34bc0d2b7e2390486cd8c416e5a8488e6ef151abec99f810d071c089800ca41899c71e29e2e2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-17 12:36:39.756352) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 12:36:39.756688) ========= 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.TfVsuxYWqJ/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.CN1NYwHi9d/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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.005019 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 12:36:39.805339) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-17 12:36:39.805663) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-17 12:36:39.806098) ========= ========= Total master_lag: 0.19 =========