builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0290 starttime: 1480593370.5 results: success (0) buildid: 20161201004019 builduid: d0a18ee4297b4ea883fece53f621d9ca revision: fa39ccc915c12f39eb51bd55871266f23fb5e999 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:10.501953) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:10.502458) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:10.502769) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:10.530117) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:10.530541) ========= 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.WR2aCq0kmG/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.NGVDkJPdYD/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-01 03:56:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 21.2M=0.001s 2016-12-01 03:56:10 (21.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.358249 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:10.954136) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:10.954464) ========= 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.WR2aCq0kmG/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.NGVDkJPdYD/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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.024578 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:11.001066) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-01 03:56:11.001952) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fa39ccc915c12f39eb51bd55871266f23fb5e999 --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 fa39ccc915c12f39eb51bd55871266f23fb5e999 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WR2aCq0kmG/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.NGVDkJPdYD/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-01 03:56:11,101 truncating revision to first 12 chars 2016-12-01 03:56:11,101 Setting DEBUG logging. 2016-12-01 03:56:11,101 attempt 1/10 2016-12-01 03:56:11,101 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fa39ccc915c1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-01 03:56:12,339 unpacking tar archive at: mozilla-aurora-fa39ccc915c1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.520981 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-01 03:56:12.548314) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:12.548859) ========= script_repo_revision: fa39ccc915c12f39eb51bd55871266f23fb5e999 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:12.549512) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:12.549980) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:12.572187) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-01 03:56:12.572601) ========= /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.WR2aCq0kmG/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.NGVDkJPdYD/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:56:12 INFO - MultiFileLogger online at 20161201 03:56:12 in /builds/slave/test 03:56:12 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 03:56:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:56:12 INFO - {'append_to_log': False, 03:56:12 INFO - 'base_work_dir': '/builds/slave/test', 03:56:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:56:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:56:12 INFO - 'branch': 'Mozilla-Aurora', 03:56:12 INFO - 'buildbot_json_path': 'buildprops.json', 03:56:12 INFO - 'config_files': ('talos/mac_config.py',), 03:56:12 INFO - 'default_actions': ('clobber', 03:56:12 INFO - 'read-buildbot-config', 03:56:12 INFO - 'download-and-extract', 03:56:12 INFO - 'populate-webroot', 03:56:12 INFO - 'create-virtualenv', 03:56:12 INFO - 'install', 03:56:12 INFO - 'run-tests'), 03:56:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:56:12 INFO - 'download_minidump_stackwalk': True, 03:56:12 INFO - 'download_symbols': 'ondemand', 03:56:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:56:12 INFO - 'tooltool.py': '/tools/tooltool.py', 03:56:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:56:12 INFO - '/tools/misc-python/virtualenv.py')}, 03:56:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:56:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:56:12 INFO - 'installer_path': 'installer.exe', 03:56:12 INFO - 'log_level': 'info', 03:56:12 INFO - 'log_name': 'talos', 03:56:12 INFO - 'log_to_console': True, 03:56:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:56:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:56:12 INFO - 'opt_config_files': (), 03:56:12 INFO - 'pip_index': False, 03:56:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:56:12 INFO - 'cmd': ('bash', 03:56:12 INFO - '-c', 03:56:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:56:12 INFO - 'enabled': True, 03:56:12 INFO - 'halt_on_failure': False, 03:56:12 INFO - 'name': 'check_screen_resolution'},), 03:56:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:56:12 INFO - 'cmd': ('bash', 03:56:12 INFO - '-c', 03:56:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:56:12 INFO - 'enabled': True, 03:56:12 INFO - 'halt_on_failure': False, 03:56:12 INFO - 'name': 'check_screen_resolution'},), 03:56:12 INFO - 'run_cmd_checks_enabled': True, 03:56:12 INFO - 'sps_profile': False, 03:56:12 INFO - 'sps_profile_interval': 0, 03:56:12 INFO - 'suite': 'dromaeojs-e10s', 03:56:12 INFO - 'system_bits': '32', 03:56:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:56:12 INFO - 'title': 't-yosemite-r7-0290', 03:56:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:56:12 INFO - 'use_talos_json': True, 03:56:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:56:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:56:12 INFO - 'work_dir': 'build'} 03:56:12 INFO - [mozharness: 2016-12-01 11:56:12.777612Z] Running clobber step. 03:56:12 INFO - Running pre-action listener: _resource_record_pre_action 03:56:12 INFO - Running main action method: clobber 03:56:12 INFO - rmtree: /builds/slave/test/build 03:56:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:56:15 INFO - Running post-action listener: _resource_record_post_action 03:56:15 INFO - [mozharness: 2016-12-01 11:56:15.175018Z] Finished clobber step (success) 03:56:15 INFO - [mozharness: 2016-12-01 11:56:15.175141Z] Running read-buildbot-config step. 03:56:15 INFO - Running pre-action listener: _resource_record_pre_action 03:56:15 INFO - Running main action method: read_buildbot_config 03:56:15 INFO - Using buildbot properties: 03:56:15 INFO - { 03:56:15 INFO - "project": "", 03:56:15 INFO - "product": "firefox", 03:56:15 INFO - "script_repo_revision": "production", 03:56:15 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 03:56:15 INFO - "repository": "", 03:56:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 03:56:15 INFO - "buildid": "20161201004019", 03:56:15 INFO - "stage_platform": "macosx64", 03:56:15 INFO - "basedir": "/builds/slave/test", 03:56:15 INFO - "nightly_build": "True", 03:56:15 INFO - "buildnumber": 472, 03:56:15 INFO - "slavename": "t-yosemite-r7-0290", 03:56:15 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:56:15 INFO - "platform": "yosemite_r7", 03:56:15 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:56:15 INFO - "branch": "mozilla-aurora", 03:56:15 INFO - "repo_path": "releases/mozilla-aurora", 03:56:15 INFO - "revision": "fa39ccc915c12f39eb51bd55871266f23fb5e999", 03:56:15 INFO - "pgo_build": "False", 03:56:15 INFO - "builduid": "d0a18ee4297b4ea883fece53f621d9ca", 03:56:15 INFO - "slavebuilddir": "test" 03:56:15 INFO - } 03:56:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:56:15 INFO - Running post-action listener: _resource_record_post_action 03:56:15 INFO - [mozharness: 2016-12-01 11:56:15.178507Z] Finished read-buildbot-config step (success) 03:56:15 INFO - [mozharness: 2016-12-01 11:56:15.178624Z] Running download-and-extract step. 03:56:15 INFO - Running pre-action listener: _resource_record_pre_action 03:56:15 INFO - Running main action method: download_and_extract 03:56:15 INFO - mkdir: /builds/slave/test/build/tests 03:56:15 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')]} 03:56:15 INFO - trying https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:56:15 INFO - Downloading https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/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 03:56:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/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 03:56:16 INFO - Downloaded 1199 bytes. 03:56:16 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:56:16 INFO - Using the following test package requirements: 03:56:16 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:56:16 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:56:16 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:56:16 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:56:16 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:56:16 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:56:16 INFO - u'jsshell-mac.zip'], 03:56:16 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:56:16 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:56:16 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:56:16 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:56:16 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:56:16 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:56:16 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:56:16 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:56:16 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:56:16 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:56:16 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:56:16 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:56:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:56:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:56:16 INFO - Fetch https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:56:17 INFO - Http code: 200 03:56:17 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 03:56:17 INFO - x-amz-cf-id: Kf4FyAYjg49BtJreRS9gTvFWCzRQzKrumMQ210-qGxAlwxrvA87L3g== 03:56:17 INFO - x-amz-version-id: xdguqhcwyCQTII8YxclVDN1qtES43Mme 03:56:17 INFO - Expected file size: 16285400 03:56:17 INFO - Obtained file size: 16285400 03:56:24 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 03:56:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:56:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:56:24 INFO - Fetch https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:56:24 INFO - Http code: 200 03:56:24 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 03:56:24 INFO - x-amz-cf-id: N5YJKQIqrNa3puiQeXV-v8Vj8y9sExkldxDNv4XGrV8X0-R29JWCgQ== 03:56:24 INFO - x-amz-version-id: xdguqhcwyCQTII8YxclVDN1qtES43Mme 03:56:25 INFO - Expected file size: 16285400 03:56:25 INFO - Obtained file size: 16285400 03:56:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:56:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:56:31 INFO - Fetch https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:56:32 INFO - Http code: 200 03:56:32 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 03:56:32 INFO - x-amz-cf-id: VQgyGw2LRRCbLPBKjSgF1Z02z3VBwJmYeKLT7-JXsLdbxraF7Z5O9g== 03:56:32 INFO - x-amz-version-id: e0FjGJzkluJxeWrtIc5Sm.sp1A6QYfnA 03:56:32 INFO - Expected file size: 14409038 03:56:32 INFO - Obtained file size: 14409038 03:56:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:32 INFO - trying https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:56:32 INFO - Downloading https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:56:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/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 03:56:36 INFO - Downloaded 109854203 bytes. 03:56:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:56:36 INFO - mkdir: /builds/slave/test/properties 03:56:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:56:36 INFO - Writing to file /builds/slave/test/properties/build_url 03:56:36 INFO - Contents: 03:56:36 INFO - build_url:https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:56:36 INFO - Running post-action listener: _resource_record_post_action 03:56:36 INFO - Running post-action listener: set_extra_try_arguments 03:56:36 INFO - [mozharness: 2016-12-01 11:56:36.800602Z] Finished download-and-extract step (success) 03:56:36 INFO - [mozharness: 2016-12-01 11:56:36.800742Z] Running populate-webroot step. 03:56:36 INFO - Running pre-action listener: _resource_record_pre_action 03:56:36 INFO - Running main action method: populate_webroot 03:56:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:56:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:56:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:56:36 INFO - '--tppagecycles', 03:56:36 INFO - '7'], 03:56:36 INFO - 'tests': ['tsvgm']}}, 03:56:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:56:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:56:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:56:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:56:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:36 INFO - 'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:56:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:56:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:36 INFO - 'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['damp', 'tps']}, 03:56:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:36 INFO - 'tests': ['damp', 'tps']}, 03:56:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['dromaeo_dom']}, 03:56:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:56:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['basic_compositor_video']}, 03:56:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:56:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['a11yr', 03:56:36 INFO - 'ts_paint', 03:56:36 INFO - 'tpaint', 03:56:36 INFO - 'sessionrestore', 03:56:36 INFO - 'sessionrestore_no_auto_restore', 03:56:36 INFO - 'tabpaint']}, 03:56:36 INFO - 'other-e10s': {'tests': ['a11yr', 03:56:36 INFO - 'ts_paint', 03:56:36 INFO - 'tpaint', 03:56:36 INFO - 'sessionrestore', 03:56:36 INFO - 'sessionrestore_no_auto_restore', 03:56:36 INFO - 'tabpaint']}, 03:56:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:56:36 INFO - 'ts_paint', 03:56:36 INFO - 'tpaint', 03:56:36 INFO - 'sessionrestore', 03:56:36 INFO - 'sessionrestore_no_auto_restore', 03:56:36 INFO - 'tabpaint']}, 03:56:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:56:36 INFO - 'ts_paint', 03:56:36 INFO - 'tpaint', 03:56:36 INFO - 'sessionrestore', 03:56:36 INFO - 'sessionrestore_no_auto_restore', 03:56:36 INFO - 'tabpaint']}, 03:56:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['a11yr', 03:56:36 INFO - 'ts_paint', 03:56:36 INFO - 'tpaint', 03:56:36 INFO - 'sessionrestore', 03:56:36 INFO - 'sessionrestore_no_auto_restore', 03:56:36 INFO - 'tabpaint']}, 03:56:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['a11yr', 03:56:36 INFO - 'ts_paint', 03:56:36 INFO - 'tpaint', 03:56:36 INFO - 'sessionrestore', 03:56:36 INFO - 'sessionrestore_no_auto_restore', 03:56:36 INFO - 'tabpaint']}, 03:56:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['tsvgx', 03:56:36 INFO - 'tsvgr_opacity', 03:56:36 INFO - 'tart', 03:56:36 INFO - 'tscrollx', 03:56:36 INFO - 'cart']}, 03:56:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:56:36 INFO - 'tsvgr_opacity', 03:56:36 INFO - 'tart', 03:56:36 INFO - 'tscrollx', 03:56:36 INFO - 'cart']}, 03:56:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:36 INFO - 'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['tp5o']}, 03:56:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:36 INFO - 'tests': ['tp5o']}, 03:56:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:36 INFO - 'talos_options': ['--disable-e10s', 03:56:36 INFO - '--xperf_path', 03:56:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:56:36 INFO - 'tests': ['tp5n']}, 03:56:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:36 INFO - 'talos_options': ['--xperf_path', 03:56:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:56:36 INFO - 'tests': ['tp5n']}}, 03:56:36 INFO - 'talos.zip': {'path': '', 03:56:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:56:36 INFO - Running post-action listener: _resource_record_post_action 03:56:36 INFO - [mozharness: 2016-12-01 11:56:36.819477Z] Finished populate-webroot step (success) 03:56:36 INFO - [mozharness: 2016-12-01 11:56:36.819584Z] Running create-virtualenv step. 03:56:36 INFO - Running pre-action listener: _resource_record_pre_action 03:56:36 INFO - Running main action method: create_virtualenv 03:56:36 INFO - Creating virtualenv /builds/slave/test/build/venv 03:56:36 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:56:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:56:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:56:36 INFO - Using real prefix '/tools/python27' 03:56:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:56:37 INFO - Installing distribute.............................................................................................................................................................................................done. 03:56:40 INFO - Installing pip.................done. 03:56:40 INFO - Return code: 0 03:56:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:56:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:56:40 INFO - Reading from file tmpfile_stdout 03:56:40 INFO - Output received: 03:56:40 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) 03:56:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:56:40 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')]} 03:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:40 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')]} 03:56:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:40 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 0x1032f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f3572fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10310cd30>, '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 0x102f72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103104b20>, '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.WR2aCq0kmG/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.NGVDkJPdYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:56:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:56:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:56:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WR2aCq0kmG/Render', 03:56:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:40 INFO - 'HOME': '/Users/cltbld', 03:56:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:40 INFO - 'LOGNAME': 'cltbld', 03:56:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:40 INFO - 'MOZ_NO_REMOTE': '1', 03:56:40 INFO - 'NO_EM_RESTART': '1', 03:56:40 INFO - 'PAGER': '/bin/cat', 03:56:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:40 INFO - 'PWD': '/builds/slave/test', 03:56:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:40 INFO - 'SHELL': '/bin/bash', 03:56:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGVDkJPdYD/Listeners', 03:56:40 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 03:56:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:40 INFO - 'USER': 'cltbld', 03:56:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:40 INFO - 'XPC_FLAGS': '0x0', 03:56:40 INFO - 'XPC_SERVICE_NAME': '0', 03:56:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:41 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:56:41 INFO - Cleaning up... 03:56:41 INFO - Return code: 0 03:56:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:56:41 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')]} 03:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:41 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')]} 03:56:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:41 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 0x1032f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f3572fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10310cd30>, '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 0x102f72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103104b20>, '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.WR2aCq0kmG/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.NGVDkJPdYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:56:41 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 03:56:41 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 03:56:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WR2aCq0kmG/Render', 03:56:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:41 INFO - 'HOME': '/Users/cltbld', 03:56:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:41 INFO - 'LOGNAME': 'cltbld', 03:56:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:41 INFO - 'MOZ_NO_REMOTE': '1', 03:56:41 INFO - 'NO_EM_RESTART': '1', 03:56:41 INFO - 'PAGER': '/bin/cat', 03:56:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:41 INFO - 'PWD': '/builds/slave/test', 03:56:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:41 INFO - 'SHELL': '/bin/bash', 03:56:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGVDkJPdYD/Listeners', 03:56:41 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 03:56:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:41 INFO - 'USER': 'cltbld', 03:56:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:41 INFO - 'XPC_FLAGS': '0x0', 03:56:41 INFO - 'XPC_SERVICE_NAME': '0', 03:56:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:41 INFO - Downloading/unpacking psutil>=3.1.1 03:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:56:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:56:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:56:44 INFO - Installing collected packages: psutil 03:56:44 INFO - Running setup.py install for psutil 03:56:44 INFO - building 'psutil._psutil_osx' extension 03:56:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:56:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:56:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:56:45 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:56:45 INFO - building 'psutil._psutil_posix' extension 03:56:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:56:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:56:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:56:45 INFO - ^ 03:56:45 INFO - 1 warning generated. 03:56:45 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:56:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:56:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:56:45 INFO - Successfully installed psutil 03:56:45 INFO - Cleaning up... 03:56:46 INFO - Return code: 0 03:56:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:56: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')]} 03:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56: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')]} 03:56:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:46 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 0x1032f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f3572fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10310cd30>, '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 0x102f72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103104b20>, '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.WR2aCq0kmG/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.NGVDkJPdYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:56:46 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 03:56:46 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 03:56:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WR2aCq0kmG/Render', 03:56:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:46 INFO - 'HOME': '/Users/cltbld', 03:56:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:46 INFO - 'LOGNAME': 'cltbld', 03:56:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:46 INFO - 'MOZ_NO_REMOTE': '1', 03:56:46 INFO - 'NO_EM_RESTART': '1', 03:56:46 INFO - 'PAGER': '/bin/cat', 03:56:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:46 INFO - 'PWD': '/builds/slave/test', 03:56:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:46 INFO - 'SHELL': '/bin/bash', 03:56:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGVDkJPdYD/Listeners', 03:56:46 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 03:56:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:46 INFO - 'USER': 'cltbld', 03:56:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:46 INFO - 'XPC_FLAGS': '0x0', 03:56:46 INFO - 'XPC_SERVICE_NAME': '0', 03:56:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:56:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:56:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:56:49 INFO - Installing collected packages: mozsystemmonitor 03:56:49 INFO - Running setup.py install for mozsystemmonitor 03:56:50 INFO - Successfully installed mozsystemmonitor 03:56:50 INFO - Cleaning up... 03:56:50 INFO - Return code: 0 03:56:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:56:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1032f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f3572fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10310cd30>, '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 0x102f72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103104b20>, '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.WR2aCq0kmG/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.NGVDkJPdYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:56:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:56:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WR2aCq0kmG/Render', 03:56:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:50 INFO - 'HOME': '/Users/cltbld', 03:56:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:50 INFO - 'LOGNAME': 'cltbld', 03:56:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:50 INFO - 'MOZ_NO_REMOTE': '1', 03:56:50 INFO - 'NO_EM_RESTART': '1', 03:56:50 INFO - 'PAGER': '/bin/cat', 03:56:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:50 INFO - 'PWD': '/builds/slave/test', 03:56:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:50 INFO - 'SHELL': '/bin/bash', 03:56:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGVDkJPdYD/Listeners', 03:56:50 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 03:56:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:50 INFO - 'USER': 'cltbld', 03:56:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:50 INFO - 'XPC_FLAGS': '0x0', 03:56:50 INFO - 'XPC_SERVICE_NAME': '0', 03:56:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:50 INFO - Downloading/unpacking jsonschema==2.5.1 03:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:56:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:53 INFO - Downloading functools32-3.2.3-2.tar.gz 03:56:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:56:53 INFO - warning: no files found matching '*.txt' 03:56:53 INFO - no previously-included directories found matching 'build' 03:56:53 INFO - no previously-included directories found matching 'dist' 03:56:53 INFO - no previously-included directories found matching '.git*' 03:56:53 INFO - Installing collected packages: jsonschema, functools32 03:56:53 INFO - Running setup.py install for functools32 03:56:53 INFO - warning: no files found matching '*.txt' 03:56:53 INFO - no previously-included directories found matching 'build' 03:56:53 INFO - no previously-included directories found matching 'dist' 03:56:53 INFO - no previously-included directories found matching '.git*' 03:56:53 INFO - Successfully installed jsonschema functools32 03:56:53 INFO - Cleaning up... 03:56:53 INFO - Return code: 0 03:56:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1032f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f3572fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10310cd30>, '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 0x102f72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103104b20>, '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.WR2aCq0kmG/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.NGVDkJPdYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:56:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:56:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:56:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WR2aCq0kmG/Render', 03:56:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:53 INFO - 'HOME': '/Users/cltbld', 03:56:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:53 INFO - 'LOGNAME': 'cltbld', 03:56:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:53 INFO - 'MOZ_NO_REMOTE': '1', 03:56:53 INFO - 'NO_EM_RESTART': '1', 03:56:53 INFO - 'PAGER': '/bin/cat', 03:56:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:53 INFO - 'PWD': '/builds/slave/test', 03:56:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:53 INFO - 'SHELL': '/bin/bash', 03:56:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGVDkJPdYD/Listeners', 03:56:53 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 03:56:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:53 INFO - 'USER': 'cltbld', 03:56:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:53 INFO - 'XPC_FLAGS': '0x0', 03:56:53 INFO - 'XPC_SERVICE_NAME': '0', 03:56:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:56:54 INFO - Cleaning up... 03:56:54 INFO - Return code: 0 03:56:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:56: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')]} 03:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56: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')]} 03:56:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1032f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f3572fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10310cd30>, '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 0x102f72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103104b20>, '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.WR2aCq0kmG/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.NGVDkJPdYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:56:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:56:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:56:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WR2aCq0kmG/Render', 03:56:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:54 INFO - 'HOME': '/Users/cltbld', 03:56:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:54 INFO - 'LOGNAME': 'cltbld', 03:56:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:54 INFO - 'MOZ_NO_REMOTE': '1', 03:56:54 INFO - 'NO_EM_RESTART': '1', 03:56:54 INFO - 'PAGER': '/bin/cat', 03:56:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:54 INFO - 'PWD': '/builds/slave/test', 03:56:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:54 INFO - 'SHELL': '/bin/bash', 03:56:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGVDkJPdYD/Listeners', 03:56:54 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 03:56:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:54 INFO - 'USER': 'cltbld', 03:56:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:54 INFO - 'XPC_FLAGS': '0x0', 03:56:54 INFO - 'XPC_SERVICE_NAME': '0', 03:56:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:54 INFO - Downloading/unpacking blobuploader==1.2.4 03:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:56 INFO - Downloading blobuploader-1.2.4.tar.gz 03:56:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:56:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:56:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:58 INFO - Downloading docopt-0.6.1.tar.gz 03:56:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:56:58 INFO - Installing collected packages: blobuploader, requests, docopt 03:56:58 INFO - Running setup.py install for blobuploader 03:56:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:56:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:56:58 INFO - Running setup.py install for requests 03:56:58 INFO - Running setup.py install for docopt 03:56:59 INFO - Successfully installed blobuploader requests docopt 03:56:59 INFO - Cleaning up... 03:56:59 INFO - Return code: 0 03:56:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:56: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')]} 03:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56: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')]} 03:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:59 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 0x1032f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f3572fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10310cd30>, '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 0x102f72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103104b20>, '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.WR2aCq0kmG/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.NGVDkJPdYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:56:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:56:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:56:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WR2aCq0kmG/Render', 03:56:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:59 INFO - 'HOME': '/Users/cltbld', 03:56:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:59 INFO - 'LOGNAME': 'cltbld', 03:56:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:59 INFO - 'MOZ_NO_REMOTE': '1', 03:56:59 INFO - 'NO_EM_RESTART': '1', 03:56:59 INFO - 'PAGER': '/bin/cat', 03:56:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:59 INFO - 'PWD': '/builds/slave/test', 03:56:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:59 INFO - 'SHELL': '/bin/bash', 03:56:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGVDkJPdYD/Listeners', 03:56:59 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 03:56:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:59 INFO - 'USER': 'cltbld', 03:56:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:59 INFO - 'XPC_FLAGS': '0x0', 03:56:59 INFO - 'XPC_SERVICE_NAME': '0', 03:56:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:56:59 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-uME_R_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:56:59 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Ks5_B7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:56:59 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-L2NHnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:56:59 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-4NoOjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:56:59 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Vgwr1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:56:59 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-biyTyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:57:00 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-7wnBiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:57:00 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-3tfpDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:57:00 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-gKZRut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:57:00 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-LDiop7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:57:00 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-2iqKTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:57:00 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-hPAh0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:57:00 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-JX2NsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:57:00 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-QGlQ_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:00 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-fk1_2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:57:01 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-dSw7_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:57:01 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-uYMm9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:57:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:57:01 INFO - Running setup.py install for manifestparser 03:57:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:57:01 INFO - Running setup.py install for mozcrash 03:57:01 INFO - Running setup.py install for mozdebug 03:57:01 INFO - Running setup.py install for mozdevice 03:57:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:57:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:57:01 INFO - Running setup.py install for mozfile 03:57:01 INFO - Running setup.py install for mozhttpd 03:57:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:57:02 INFO - Running setup.py install for mozinfo 03:57:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:57:02 INFO - Running setup.py install for mozInstall 03:57:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:57:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:57:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:57:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:57:02 INFO - Running setup.py install for mozleak 03:57:02 INFO - Running setup.py install for mozlog 03:57:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:57:02 INFO - Running setup.py install for moznetwork 03:57:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:57:02 INFO - Running setup.py install for mozprocess 03:57:03 INFO - Running setup.py install for mozprofile 03:57:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:57:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:57:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:57:03 INFO - Running setup.py install for mozrunner 03:57:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:57:03 INFO - Running setup.py install for mozscreenshot 03:57:03 INFO - Running setup.py install for moztest 03:57:03 INFO - Running setup.py install for mozversion 03:57:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:57:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:57:03 INFO - Cleaning up... 03:57:03 INFO - Return code: 0 03:57:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:57: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')]} 03:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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')]} 03:57:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:03 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 0x1032f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f3572fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10310cd30>, '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 0x102f72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103104b20>, '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.WR2aCq0kmG/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.NGVDkJPdYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:57:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:57:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:57:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WR2aCq0kmG/Render', 03:57:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:03 INFO - 'HOME': '/Users/cltbld', 03:57:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:03 INFO - 'LOGNAME': 'cltbld', 03:57:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:03 INFO - 'MOZ_NO_REMOTE': '1', 03:57:03 INFO - 'NO_EM_RESTART': '1', 03:57:03 INFO - 'PAGER': '/bin/cat', 03:57:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:03 INFO - 'PWD': '/builds/slave/test', 03:57:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:03 INFO - 'SHELL': '/bin/bash', 03:57:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGVDkJPdYD/Listeners', 03:57:03 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 03:57:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:03 INFO - 'USER': 'cltbld', 03:57:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:03 INFO - 'XPC_FLAGS': '0x0', 03:57:03 INFO - 'XPC_SERVICE_NAME': '0', 03:57:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:57:04 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-3IioGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:57:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:57:04 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-dDbF48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:57:04 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)) 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:57:04 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Pe4Q_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:57:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:57:04 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-fDPScJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:57:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:57:04 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-L79qZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:57:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:57:04 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-RowJ1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:57:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:57:04 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-gUE7Gi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:57:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:57:04 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-jaOcdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:57:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:57:04 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-QiBAFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:57:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:57:05 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-PBC_ys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:57:05 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)) 03:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:57:05 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-EZ8SIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:57:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:57:05 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-38qFrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:57:05 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)) 03:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:57:05 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-swdHjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:57:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:57:05 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-RdANxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:57:05 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)) 03:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:05 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-N1iDyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:57:05 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Ophu0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:57:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:57:05 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-cJyYps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:57:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:57:05 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)) 03:57:05 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)) 03:57:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:57:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:57:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:57:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57:08 INFO - Downloading blessings-1.6.tar.gz 03:57:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:57:08 INFO - Installing collected packages: blessings 03:57:08 INFO - Running setup.py install for blessings 03:57:09 INFO - Successfully installed blessings 03:57:09 INFO - Cleaning up... 03:57:09 INFO - Return code: 0 03:57:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:57:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:57:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:57:09 INFO - Reading from file tmpfile_stdout 03:57:09 INFO - Current package versions: 03:57:09 INFO - blessings == 1.6 03:57:09 INFO - blobuploader == 1.2.4 03:57:09 INFO - docopt == 0.6.1 03:57:09 INFO - functools32 == 3.2.3-2 03:57:09 INFO - jsonschema == 2.5.1 03:57:09 INFO - manifestparser == 1.1 03:57:09 INFO - mozInstall == 1.12 03:57:09 INFO - mozcrash == 1.0 03:57:09 INFO - mozdebug == 0.1 03:57:09 INFO - mozdevice == 0.48 03:57:09 INFO - mozfile == 1.2 03:57:09 INFO - mozhttpd == 0.7 03:57:09 INFO - mozinfo == 0.9 03:57:09 INFO - mozleak == 0.1 03:57:09 INFO - mozlog == 3.4 03:57:09 INFO - moznetwork == 0.27 03:57:09 INFO - mozprocess == 0.23 03:57:09 INFO - mozprofile == 0.28 03:57:09 INFO - mozrunner == 6.13 03:57:09 INFO - mozscreenshot == 0.1 03:57:09 INFO - mozsystemmonitor == 0.3 03:57:09 INFO - moztest == 0.7 03:57:09 INFO - mozversion == 1.4 03:57:09 INFO - psutil == 3.1.1 03:57:09 INFO - requests == 1.2.3 03:57:09 INFO - wsgiref == 0.1.2 03:57:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:57:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:09 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 0x1032f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f3572fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10310cd30>, '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 0x102f72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103104b20>, '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.WR2aCq0kmG/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.NGVDkJPdYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:57:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:57:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:57:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WR2aCq0kmG/Render', 03:57:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:09 INFO - 'HOME': '/Users/cltbld', 03:57:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:09 INFO - 'LOGNAME': 'cltbld', 03:57:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:09 INFO - 'MOZ_NO_REMOTE': '1', 03:57:09 INFO - 'NO_EM_RESTART': '1', 03:57:09 INFO - 'PAGER': '/bin/cat', 03:57:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:09 INFO - 'PWD': '/builds/slave/test', 03:57:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:09 INFO - 'SHELL': '/bin/bash', 03:57:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGVDkJPdYD/Listeners', 03:57:09 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 03:57:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:09 INFO - 'USER': 'cltbld', 03:57:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:09 INFO - 'XPC_FLAGS': '0x0', 03:57:09 INFO - 'XPC_SERVICE_NAME': '0', 03:57:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:57:10 INFO - Cleaning up... 03:57:10 INFO - Return code: 0 03:57:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:57:10 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')]} 03:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:10 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')]} 03:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:10 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 0x1032f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f3572fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10310cd30>, '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 0x102f72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103104b20>, '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.WR2aCq0kmG/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.NGVDkJPdYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:57:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:57:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:57:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WR2aCq0kmG/Render', 03:57:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:10 INFO - 'HOME': '/Users/cltbld', 03:57:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:10 INFO - 'LOGNAME': 'cltbld', 03:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:10 INFO - 'MOZ_NO_REMOTE': '1', 03:57:10 INFO - 'NO_EM_RESTART': '1', 03:57:10 INFO - 'PAGER': '/bin/cat', 03:57:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:10 INFO - 'PWD': '/builds/slave/test', 03:57:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:10 INFO - 'SHELL': '/bin/bash', 03:57:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGVDkJPdYD/Listeners', 03:57:10 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 03:57:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:10 INFO - 'USER': 'cltbld', 03:57:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:10 INFO - 'XPC_FLAGS': '0x0', 03:57:10 INFO - 'XPC_SERVICE_NAME': '0', 03:57:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:57:10 INFO - Cleaning up... 03:57:10 INFO - Return code: 0 03:57:10 INFO - Running post-action listener: _resource_record_post_action 03:57:10 INFO - Running post-action listener: _start_resource_monitoring 03:57:10 INFO - Starting resource monitoring. 03:57:10 INFO - [mozharness: 2016-12-01 11:57:10.421438Z] Finished create-virtualenv step (success) 03:57:10 INFO - [mozharness: 2016-12-01 11:57:10.422492Z] Running install step. 03:57:10 INFO - Running pre-action listener: _resource_record_pre_action 03:57:10 INFO - Running main action method: install 03:57:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:57:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:57:10 INFO - Reading from file tmpfile_stdout 03:57:10 INFO - Detecting whether we're running mozinstall >=1.0... 03:57:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:57:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:57:10 INFO - Reading from file tmpfile_stdout 03:57:10 INFO - Output received: 03:57:10 INFO - Usage: mozinstall [options] installer 03:57:10 INFO - Options: 03:57:10 INFO - -h, --help show this help message and exit 03:57:10 INFO - -d DEST, --destination=DEST 03:57:10 INFO - Directory to install application into. [default: 03:57:10 INFO - "/builds/slave/test"] 03:57:10 INFO - --app=APP Application being installed. [default: firefox] 03:57:10 INFO - mkdir: /builds/slave/test/build/application 03:57:10 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'] 03:57:10 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 03:57:41 INFO - Reading from file tmpfile_stdout 03:57:41 INFO - Output received: 03:57:41 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:57:41 INFO - Running post-action listener: _resource_record_post_action 03:57:41 INFO - [mozharness: 2016-12-01 11:57:41.772765Z] Finished install step (success) 03:57:41 INFO - [mozharness: 2016-12-01 11:57:41.772919Z] Running run-tests step. 03:57:41 INFO - Running pre-action listener: _resource_record_pre_action 03:57:41 INFO - Running main action method: run_tests 03:57:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:57:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:57:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:57:41 INFO - 2016-12-01 03:57:41.787 screenresolution[1629:7567] starting screenresolution argv=screenresolution get 03:57:41 INFO - 2016-12-01 03:57:41.790 screenresolution[1629:7567] Display 0: 1600x1200x32@60 03:57:41 INFO - 2016-12-01 03:57:41.795 screenresolution[1630:7569] starting screenresolution argv=screenresolution list 03:57:41 INFO - Available Modes on Display 0 03:57:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:57:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:57:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:57:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:57:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:57:42 INFO - Intel Iris: 03:57:42 INFO - Chipset Model: Intel Iris 03:57:42 INFO - Type: GPU 03:57:42 INFO - Bus: Built-In 03:57:42 INFO - VRAM (Dynamic, Max): 1536 MB 03:57:42 INFO - Vendor: Intel (0x8086) 03:57:42 INFO - Device ID: 0x0a2e 03:57:42 INFO - Revision ID: 0x0009 03:57:42 INFO - Displays: 03:57:42 INFO - Display: 03:57:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:57:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:57:42 INFO - Display Serial Number: 1600x1200 60 03:57:42 INFO - Main Display: Yes 03:57:42 INFO - Mirror: Off 03:57:42 INFO - Online: Yes 03:57:42 INFO - Rotation: Supported 03:57:42 INFO - Return code: 0 03:57:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:57:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:57:42 INFO - Python 2.7.3 03:57:42 INFO - Return code: 0 03:57:42 INFO - grabbing minidump binary from tooltool 03:57:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:42 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 0x10310cd30>, '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 0x102f72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103104b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:57:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:57:42 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:57:42 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 03:57:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:57:42 INFO - Return code: 0 03:57:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:57:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:57:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:57:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:57:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:57:42 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/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0290', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:57:42 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/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0290 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:57:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WR2aCq0kmG/Render', 03:57:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:42 INFO - 'HOME': '/Users/cltbld', 03:57:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:42 INFO - 'LOGNAME': 'cltbld', 03:57:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:57:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:57:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:42 INFO - 'MOZ_NO_REMOTE': '1', 03:57:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:57:42 INFO - 'NO_EM_RESTART': '1', 03:57:42 INFO - 'PAGER': '/bin/cat', 03:57:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:42 INFO - 'PWD': '/builds/slave/test', 03:57:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:57:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:42 INFO - 'SHELL': '/bin/bash', 03:57:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGVDkJPdYD/Listeners', 03:57:42 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 03:57:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:42 INFO - 'USER': 'cltbld', 03:57:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:42 INFO - 'XPC_FLAGS': '0x0', 03:57:42 INFO - 'XPC_SERVICE_NAME': '0', 03:57:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:42 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/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0290', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:57:42 INFO - mozversion application_buildid: 20161201004019 03:57:42 INFO - mozversion application_changeset: fa39ccc915c12f39eb51bd55871266f23fb5e999 03:57:42 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:57:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:57:42 INFO - mozversion application_name: Firefox 03:57:42 INFO - mozversion application_remotingname: firefox-dev 03:57:42 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:57:42 INFO - mozversion application_vendor: Mozilla 03:57:42 INFO - mozversion application_version: 52.0a2 03:57:42 INFO - mozversion platform_buildid: 20161201004019 03:57:42 INFO - mozversion platform_changeset: fa39ccc915c12f39eb51bd55871266f23fb5e999 03:57:42 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:57:42 INFO - mozversion platform_version: 52.0a2 03:57:42 INFO - using testdate: 1480593462 03:57:42 INFO - actual date: 1480593462 03:57:42 INFO - starting webserver on 'localhost:49253' 03:57:42 INFO - SUITE-START | Running 2 tests 03:57:42 INFO - TEST-START | dromaeo_css 03:57:42 INFO - operating with platform_type : mac_ 03:57:42 INFO - Initialising browser for dromaeo_css test... 03:57:42 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmp8kSfym/profile http://localhost:49253/getInfo.html) 03:57:45 INFO - PROCESS | 1640 | 2016-12-01 03:57:45.454 plugin-container[1643:7754] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x934b, name = 'com.apple.tsm.portname' 03:57:45 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:57:45 INFO - PROCESS | 1640 | 2016-12-01 03:57:45.455 plugin-container[1643:7754] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9713, name = 'com.apple.CFPasteboardClient' 03:57:45 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:57:45 INFO - PROCESS | 1640 | 2016-12-01 03:57:45.455 plugin-container[1643:7754] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:57:45 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 03:57:45 INFO - PROCESS | 1640 | colorDepth:24 03:57:45 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 03:57:45 INFO - PROCESS | 1640 | __metrics 03:57:45 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 03:57:46 INFO - TEST-INFO | 1640: exit 0 03:57:46 INFO - Browser initialized. 03:57:46 INFO - Running cycle 1/1 for dromaeo_css test... 03:57:46 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmp8kSfym/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:57:49 INFO - PROCESS | 1644 | 2016-12-01 03:57:49.376 plugin-container[1645:7887] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 03:57:49 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:57:49 INFO - PROCESS | 1644 | 2016-12-01 03:57:49.376 plugin-container[1645:7887] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 03:57:49 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:57:49 INFO - PROCESS | 1644 | 2016-12-01 03:57:49.377 plugin-container[1645:7887] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:57:58 INFO - PROCESS | 1644 | RSS: Main: 229355520 03:57:58 INFO - PROCESS | 1644 | 03:58:48 INFO - PROCESS | 1644 | 1480593528476 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:01:02 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49253/tests/dromaeo/cssquery-ext.html) 04:01:05 INFO - PROCESS | 1644 | RSS: Main: 233652224 04:01:05 INFO - PROCESS | 1644 | 04:04:07 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-ext.html (next: http://localhost:49253/tests/dromaeo/cssquery-jquery.html) 04:04:08 INFO - PROCESS | 1644 | RSS: Main: 243019776 04:04:08 INFO - PROCESS | 1644 | 04:07:10 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49253/tests/dromaeo/cssquery-mootools.html) 04:07:10 INFO - PROCESS | 1644 | RSS: Main: 245841920 04:07:10 INFO - PROCESS | 1644 | 04:10:12 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49253/tests/dromaeo/cssquery-prototype.html) 04:10:13 INFO - PROCESS | 1644 | RSS: Main: 245108736 04:10:13 INFO - PROCESS | 1644 | 04:13:16 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49253/tests/dromaeo/cssquery-yui.html) 04:13:16 INFO - PROCESS | 1644 | RSS: Main: 249700352 04:13:16 INFO - PROCESS | 1644 | 04:16:19 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-yui.html (next: http://localhost:49253/tests/dromaeo/cssquery-dojo.html) 04:16:20 INFO - PROCESS | 1644 | RSS: Main: 252059648 04:16:20 INFO - PROCESS | 1644 | 04:16:20 INFO - PROCESS | 1644 | __start_tp_report 04:16:20 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 04:16:20 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 04:16:20 INFO - PROCESS | 1644 | |i|pagename|runs| 04:16:20 INFO - PROCESS | 1644 | |0;dojo.html;3103.1156120505652;3377.105067157204;3409.045467269164;3410.686885549063;3412.522246885435;4984.850454486362;5295.020421222077;5629.35262444819;5644.520215781658;5666.093425051992;5733.426657334265;5787.47912687858;5788;5799.217105690741;5822.883542329151;5224.190250511177;5252.579793616521;5271.262023316739;5318.25544423781;5326.3075800145925;5323.840284791462;5338.813141540047;5355.4912283333215;5363.329583802025;5367.007103685831;5341.4658534146465;5343.519083282504;5344.0915044442345;5347.919781203285;5393.73031348433;5143.357080364935;5198.662086964335;5254.395744489356;5300.893982120336;5338.599605029619;5162.664426812245;5251.501107394792;5261.710605916673;5268.209768534751;5298;5669.348024977139;5770.307563092414;5783.987802134609;5808.941910580925;5815.709214539298;5986.401359864022;6143.0820360328935;6143.573288550997;6172.290186628506;6199.504039676815;6117.248930028882;6184.907226391608;6203.410675985774;6245.281792593864;6261.655608941506;5845.415458454163;5866.911996320058;5868.618539794942;5873.618214816023;5882.205902203191;6170.290416602058;6303.842403939892;6309.148264984215;6333.809985700436;6334.683265836782;5107.004134193797;5477.397486276506;6051.031834906482;6117.296510901214;6134.294556126102;4578.88552786183;6164.599301045418;6183.412575805291;6215.906761398583;6252.5935814171935;5737.225474560964;6207.81376558704;6238;6277.686115694197;6291.056341548804;5363.517283444508;5864.266966629171;6055.152278680988;6276.529260305495;6296.779612713533;3820.1977584707524;4002.3396139636634;4206.453161089039;4800.3759511263315;4999.52504512078;3693.8707145249787;3927.5876033016693;4344.56554344563;4376.212281789309;4428.557144285545;4775.761211939389;5763.682997435181;5939.851503712442;5939.910901336483;5983.700814959235;6153.784617538454;6197.1324014637985;6214.130021796952;6234.064890266405;6274;5909.3204281507315;5945.078512830518;5959.314678811992;5962.791302304398;5988.520918326523;5406.405295417501;5519.972400137974;5537.723113844291;5539.279893613804;5540;6209.316975132641;6209.906851397142;6263.592866463665;6293.874122517615;6369.490440764635;6204.81385558434;6258.843528911813;6277.591956522994;6283.748650053946;6289.811305660837;6277.309495955533;6300.086487459201;6336.461400780985;6384.553081284265;6389.7124629390855;6074.757009719562;6075.2405949256345;6112.229929638781;6137.315698286874;6161.691915404302;5789.189513468202;6260.029695397221;6267.1539342188225;6281.623102613858;6290.2766181889665;6166.229221347332;6174.135621013152;6178.721957511832;6183.690815459299;6202.844928876814;5959.374265702038;6038.426349496791;6053.97082495968;6058.333583305921;6093.6039157454625;5820.097884827858;5934.43622855828;5967.433093856077;5981.312149102735;5991.520678345635;6112.694365281629;6121.32665406796;6122.306673174842;6127.448529632355;6130.540209484396;5900.144479050427;5908.291005079418;5946.1972633693895;5965.2245208124405;5966.612170208922 04:16:20 INFO - PROCESS | 1644 | |1;ext.html;467.85730352242575;508.59058457941364;520.8333333333334;530.8434011966469;540.2706346432315;28800.27999300016;38594.614053859485;38646.420303695464;38691.61308386915;38783.224335513274;39370.80314598423;39739.8078057658;40022.19955600886;40082.997925051815;40251.19497610053;9036.141566551167;9172.816543669123;9191.954040229823;9204.861927071099;9206.631734730603;9131.58907849145;9175.908240917606;9220.769480763001;9232.446053236794;9239.214666753334;9174.220191283748;9211.124943130391;9222.123898229658;9228.492432916188;9230.67692630758;7829.882551761728;7981.680732770682;8642.178992995718;9218;9237.815243695155;9186.586603602855;9284.53577321138;9299.48852813095;9307.255419566418;9308.208802251816;38117.80941095304;38408.231835363134;38595.61404385948;38601.806990965146;38614.42078368827;2837.3757773289844;3266.934661306784;3349.1627093226693;3375.949360759566;3413.4709120086427;28569.580640507054;29418.558721619193;29627.70372296271;29644;30033.399332013454;2727.931801704973;3142.858571364294;3152.133163380066;3176.2218256527067;3178.761592880543;504537;512828;512916;514333;514449.42775286257;56694;57000.42999569951;57035.71482142563;57039.14441283384;57193;58488.41511584915;59334.406655932886;59368.70315648394;59520.40479595235;59614.701926490954;14651.487197948021;14854.554363369116;14885.553433397015;14888;14942;11419.714507137222;11459;11466.140039497071;11473.942630286796;11477.885221147682;14772;15215.543533693786;15279.465218717514;15354.616134596501;15521.611959700873;3125.6092988376454;5865.853353666107;6104.389561043949;6122.142899994004;6141.508679305645;872.9694460693972;959.9424034557809;981.4454833019321;1062.984055239172;1156.612534800851;29778.55332170019;29791.957281495044;29821;30159.547606785916;30249;1004.4726518577805;1017.0846238385512;1023.4729114506035;1096.8793432722393;1101.614434947778;14427.92786036063;14705.926470367795;14876.330565124596;14922.104673719612;14926;618896;620774;625054;626465;627416;52842;53127.20309195365;53212.201816972774;53223.73388133112;53278.46721532813;1348.0967751606447;1350.1763924006348;1368.8699573540498;1372.6362513933816;1372.6774208061097;27564.586531201647;28260.434791303647;28938;29478.705212947596;30716;51703;52960;53052;54266;56923.71538142283;657264;660648;662278;663669;665604;40040;40580.59419405768;40899.7955010223;40987.59012409838;41047.794761026;44839.55160448484;45094;45313.320300194864;45683.31475027944;45784.313235302165;36610.45084323791;37049.07377315481;37754.8112259437;38836.805815970736;39475.81572552828;36236.81881590575;37738.62261377461;37799.433008505446;38545;39545.01137471471;35555.64444355523;36052;36643.63356366402;36814.71148509789;36848.44727329039;29413.85293073521;31912.202194945312;34156.609037368136;34159.829200853834;34427.13932151716;22598.19214462806;24672;25193.244202673948;25346.619800702636;25493.235202943943 04:16:20 INFO - PROCESS | 1644 | |2;jquery.html;286.9683487861139;318.96650851660587;361.91675914539655;372.33538134430046;389.7232964595139;18659.813401865977;18966.431007069776;20313.59372812543;20518.38444846657;20580.176792928265;18647.533811654714;18838.340658076937;19375.612487750237;19629.11668974896;19701.31045413411;5712.428757124286;5994.820155395323;5999.070144127667;6000.669963152025;6014.578979471439;5700.771969121251;5825.650460972355;5857.6485410875275;5888.558358123137;5889.175515427822;5741.540676745871;5805.680687562182;5823.621464604808;5848.5321174305955;5857.589968702171;5543.168524721304;5844.824655260328;5848.941510584882;5863.530917526609;5875.089361148986;4463.910721785579;4792.640551958617;5140.974295128501;5154.819581314636;5168;24390;24701.258962230982;24749.381265468328;25057;25135.99456021757;2414.0705828256073;2494.962575561368;2700;2854.9286267843263;3014.9849250753823;14940.402383904631;14981.700365992729;15059.247037648074;15328.612944783523;15657.217139143111;1787.4637608717333;1815.9001254930974;1836.2563161919445;1892.6877065284211;1896.5637903282322;98054;100869;101152;101342.97314053752;103127;10503.317284376491;10514.947425262824;10933.453327333602;10955.452227388758;11125.72185695348;13254.271015094262;13409.195448273134;13502.324883755773;13505.32473376347;13520.12119212248;7808.726694565663;8040.798980025429;8135.186481351818;8225.971753530808;8248.298894593907;7972.36221102322;7990.280874721187;8055.154208808108;8078.919210807934;8108.310793582612;6449.129367535416;6643.634600096944;6658.5671931324305;6660.766873159416;6665.600063996176;1826.9543261418305;1835.6236971420826;1855.3784482198614;1869.9439016829517;1898.1742941820353;827.9195649677109;879.9340049496313;890.3900827932887;915.6428992692855;922.0502882031518;17815;19579.706304405445;21859.344219673436;21990.340289791333;22082.44793880166;950.8811398575178;956.9808603827885;961.918236949853;982.5480279071564;1072.7050061233224;10953.726156846142;11697.23967942081;11758;11763.470643821049;11783.351915644722;522760;567036;570687;589375;589688;106983.46508267408;106987;112459;113038.43480782544;114338;878.892595329889;976.7069879036261;1002.8746406699308;1008.1330056151704;1008.8284991551307;18296.993665348535;19138.425847224604;19517.609647807247;19578.60842783165;19618.60762784765;50949.23576146435;51096.744516277184;51685.7415712919;51861.70345741387;51965.05245120949;69859.65070174617;71779.64110179416;73295;73856.89214661892;74084;7636.541807491568;7760.185180556186;7800.492967957065;8036.879446808186;8302.667893284442;12432.502699891904;13018.934905325414;13102.475900963855;13117.95056395467;13354.198748075147;10875.02124808771;11228.494717737885;11298.83051754208;11519.19365644397;11747.706307342043;10809.945950270197;11090.722731931766;11310.92546208109;11415.086793056369;11506.367149806842;10522.684664416947;11165.665030049111;11317.320960742381;11663.358515281741;11920.403979801149;5439.4288599696465;6577.868442631025;6833.794986150423;6982.895256571257;7405.5926924019695;8879.556022198993;11217.66347009591;11226.719332016764;11309.830352544883;11566.248193867372 04:16:20 INFO - PROCESS | 1644 | |3;mootools.html;1413.0885578801672;1433.81360423145;1611.8549330560254;1725.6289897672013;1773.716205407135;7351.889721654179;7605.619719014041;7856.057273127226;8331.458455200407;8340;7774.494657847236;8046.396520260974;8055.798605034877;8110.675572977088;8155.0213974323015;1765.3732924811668;2107.473131717071;2255.289583781112;2266.580682573729;2304.942376440594;2279.384566167142;2304.677345171677;2329.9301020969397;2342.0397636968846;2344.765523447659;2286.371247906822;2288.965665515019;2309.284121922201;2322.082777302964;2325.1396983116188;2269.1830940861278;2281.041962375798;2305.446692793726;2306.3196357074703;2314.3982564533176;2252.2680128958154;2270.4664403865077;2277.986296098237;2281.6349384098467;2282.155602427096;8148;8191.672333106669;8197.303229225523;8266.256036956642;8287.461315014505;4825.927611085836;4996.300517927493;5002.474740152305;5003.399592048978;5008.874778130539;3008.7893847430687;3271.362084393544;3472.6353732858192;3479.9478007829903;3485.529453523767;1029.7219750667352;1049.3651340938698;1075.1560025380038;1084.69628504019;1107.03687791621;9437.858432123523;10618.734531636694;10666.200034997328;10675.78648427035;10749.086327662159;3761.887143385703;4346.7609281489185;4559.475660299042;4579.267317229227;4588.77056147198;9060.954695226614;9107.635694572144;9142.542872856331;9145.954270228607;9187.402818816756;5051.898962020812;5056.898862022739;5060.3927528696795;5065.797368105234;5084.44071152173;3735.7571757835653;4151;4622.19111655459;4644.885227545432;4652.441706995114;3716.2567486502808;3743.1016556026357;3851.9229615407535;3854.383298672199;3857.61423857612;1233.2292317301687;1252.1673087396962;1259.2822091407809;1265.2408554867006;1271.993640031794;789.5223389849174;883.7216276872764;892.6429428228761;912.2428384440898;916.2074805293373;5477.233187353773;5479.589030822704;5489.862753431116;5644;5724.112762521816;417.2822744878804;422.4824589877437;434.8825817029384;440.81706091972114;441.9005723712139;3252.7723059385617;3546.9004608571427;3638.381475149231;3675.9816200918826;3777.490038844776;9396.90603093974;9763.853542196874;9898.406095634286;9907.45508997012;9943.85645650745;9872.259580531489;9897.35667181631;9908.35595686278;9966.402015878926;9979.501024948724;6751.93248067513;7284.599347035964;7444.739434119671;7522.774316770506;7724.884126738103;5388.56891448691;5418.620696551204;5470.234167216672;5595.608307418442;5642.88714225705;9022.413543119676;9039.45763254207;9196.540172991457;9212.953935230282;9265.768355791159;8856.557172141496;9163.404378715362;9196.724098277062;9209.2172165364;9339.112784285482;6987.475939304471;7227.602481863548;7550.7734767957045;7672.347850432776;7685.884711729441;7420.888686669597;7565.092188937363;7682.154962953959;7754.030746156731;7754.379649628129;6882;6935.2717964615;7017.193022802443;7023.262557431601;7045.225025247116;6728.125343705287;6786.253512113763;6840;6855.143107111611;6925.27284635106;6413.775517856852;6424.518161138027;6430.196225471816;6442.484601231984;6489.415952564292;4648.930266045944;4655.86032419028;4716.410448693913;4745.860993361499;4810.494898035656;4721.102990431807;4736.313234580897;4740.478547359857;4803.903921921612;4870.464248932685 04:16:20 INFO - PROCESS | 1644 | |4;prototype.html;256.874131675479;297.05092230324095;300.6857833563927;304.53102222577223;309.0358082781721;5482.177673349;5523;5544.251526043983;5548.944510554898;5554.611177217596;4617.353570500132;5117.848484091087;5419.918701219485;5423.83728488145;5450.618456708032;3357.5803024621923;3932.665723413513;3991.4012898065234;4022.1754540319166;4068.0643452006057;3390.3219356128748;4110.835566577333;4129.834806607732;4134.545200027995;4143.979280103596;4080.8367665293354;4102.282100632392;4125.484314460692;4146.9792651036705;4151.605597468236;3145.5596216529475;3452.46486794546;4062.2890994076215;4091.018155642654;4165.1669666066755;3480.5127282180515;3481.3037392521596;3482.512448257246;3497.1431999160113;3497.545319108525;4829.203181475053;5264.763085661154;5279.70961597112;5328.573714102862;5356.223347614574;3979.263836190314;3997.700172487075;4332.826686932551;4635.122775276019;4669.509701481329;4498.932516012263;4595.333676616872;5706.885862282773;5740.9712951435395;5746.712664366807;3849.326367885637;3871.632194941476;3871.9806400968096;3877.185790983897;3886;5160.838811267472;5581.2186293918885;5728.541716662657;6103.572749907508;6115.510759139258;3150.5904232450093;3795.525559305087;3864.3623802072907;3870.4000879863665;3922.3724204127775;3311.8243023726714;3937.2912875682077;3946.5461471931094;3950;3974.8012599369918;3907.531096268466;3928.489296391451;4599.6771630326475;4622.791974361162;4655.255159174516;5011.423686276052;5285.365756109291;5829.329627092854;5872.706364681749;5879.676617785977;1109.4412350647192;1204.4459548607563;1256.6795467156;1280.8527019392889;1288.9291088990008;664.867026594683;823.6697733161021;851.000074911976;863.4430792139035;867.137198487509;4361.847335343247;5006.973570418056;5057.797688092435;5226.529612334908;5513.255710479114;5589.944100558942;5866.794662186803;5914.822555323347;5989.55078369124;5996.760129594767;4965.1311020571975;5205.9219111713355;5391.892162156735;5452.263944367539;5496.80761173357;2959;2994.7903646744517;2998.295400580905;3007.8195308281133;3008.2629755709554;5090.160123579642;5841.970790146107;5940.673183060027;5968.671723055187;5969.611975221596;5298.310994769657;5746.655200687972;6161.630302181883;6187.659678717624;6220.813375598739;5378.757955891916;5481.479259470424;5540.806071787468;6260.718267677966;6288.365025093417;4559.977200114111;5098.337216161875;5167.9922415128685;5251.238570407346;5290.947090529045;5432.859099589131;5923.851903702442;5988.610740301867;5993.550483713826;5996.430339117776;5276.577873770166;6317.684115794284;6322.795341098118;6335.90496142549;6364.6181229126405;3283.9343213135126;3463.653634636617;3801.239752049546;3914.64768170866;3949.980250098731;5650.40670729584;5992.820215393545;6015.939840601538;6048.348838718936;6055.303640081271;5891.558133139946;5908.7045647718305;5908.754673224147;5911.733971971185;5917.526597872246;5128.615353848401;5594.321703488856;5751.597388182787;5882.1765252771775;5886.440788125121;4950.45544990058;5600.887982240414;5733.483986441241;5738.856528586819;5756.532084318472;5185.533302002837;5407.242985981887;5728.942710573007;5817.738201780845;5833.183354330312;4751.239801631722;5408.810061786558;5655.85860353478;5666.206731057573;5709.429057094258 04:16:20 INFO - PROCESS | 1644 | |5;yui.html;589.8171566814287;701.6702149989503;739.7558805594155;759.9848003039939;796.9203079692028;13871.514496992608;14437.63905902353;14459.638509037279;14537;14781.334839932202;13263.602091937233;13300.667483312922;13376.197428154317;13384.397702103428;13472.989525785557;632.1497585747186;655.1155939481686;791.6160662078887;809.5142914251446;819.0785366462729;681.8431760695044;703.7712743358403;817.5748610722421;831.2394159344223;832.1096426823301;856.1951765340591;869.7043005378201;875.9430637008605;875.9737207883742;878.3939082033409;859.7936495241129;870.3907264914585;874.4665753890122;874.6938571499987;876.5967654878764;626.7837596029362;650.4113777031794;652.1424327010014;652.774792696519;654.5647144648802;11623.360715160663;13520.459181632821;13550.186988780704;13567.45730170792;13602.659933501644;13019.934900325532;13287.269200193985;13325.400356983959;13344.933275333562;13442.395092220873;11099.667009989713;11181.552737890475;11264;11328.150388720796;11717.472713727817;3588.2464682416726;3652.105234217607;3676.246369494247;3693.8891833244775;3708.6662200401825;73915.63042184808;75331;84106.57946710289;84597.57701211455;85477.57261213717;9966.601335946625;10388.065074143364;10413.5834566618;10415.531301091469;10467.42429166388;19604.313849015216;19628.411147665593;19796.70304945427;19806.50483737918;19879.403617891487;7312.305330993547;7393.223711510322;7545.283198096172;7662.310392064741;7743.419243556756;4211.957880421157;4273.294906340481;4280.315149576115;4299.247631664446;4406.20688276107;2924.722151395614;3294.8352582370785;3372.6795954384293;3403.591569011685;3429.0398688367295;632.7089538812198;635.7171058878847;639.0414378432351;648.614074625597;648.8021153548119;632.9272133704591;633.6144416272737;649.7303618998158;652.5040968863698;654.4862283107738;8670.86993695095;8707.782305442288;8908.910910890938;9005.099490051074;9067.637294508279;621.8134559632092;640.6284355074046;664.6909187227963;671.8589096289736;674.6222115615271;3733.533308336458;4211.873643790691;4405.537418571068;4430.490493593214;4466.1067786442845;89582.55208723915;92096.69758256097;92472;94628.52685736527;114979;59101.70449147727;62554.687226563576;77585;80682;83585;56398.15402768962;75095;79806;84066.15933840584;84819;9122.589483473392;9424.905750942402;9505.477198754135;9545.379550329206;9599.088086631758;51015;52009.77281698024;52549.7372513135;53887.191692123866;53910.46089539054;63418;68034.65982670055;71863;74545.6272718633;77635.22364776506;10228.897711022795;10420.32267902584;11990.40047997614;12029.819552706891;12084.274943503418;13512.189268643913;13558.457661693421;13582.388792504164;13767.518136865161;15020.324085415963;12172.14794964344;12633.17884337552;13021.348932553145;13157.276349800853;13972.37124329428;10798.838017429587;11188.552457901593;12517.436715348013;13138.343082846011;13495.730085398041;12455.377231138587;12842.229466232056;12869.806952895528;12940.417681204557;13565.796513052115;10567.260291779501;10583.583553824805;10706.839397408889;11085.667429977113;11135;10786.352818830896;10855.424214870163;11050.06074483678;11051.226414150933;11110.055645270242 04:16:20 INFO - PROCESS | 1644 | __end_tp_report 04:16:20 INFO - PROCESS | 1644 | __start_cc_report 04:16:20 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,362 04:16:20 INFO - PROCESS | 1644 | __end_cc_report 04:16:20 INFO - PROCESS | 1644 | __startTimestamp1480594580023__endTimestamp 04:16:20 INFO - PROCESS | 1644 | 04:16:20 INFO - PROCESS | 1644 | ------- Summary: start ------- 04:16:20 INFO - PROCESS | 1644 | Number of tests: 6 04:16:20 INFO - PROCESS | 1644 | 04:16:20 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5707.43 Median:5946.20 stddev:690.52 (11.6%) stddev-sans-first:661.72 04:16:20 INFO - PROCESS | 1644 | Values: 3103.1 3377.1 3409.0 3410.7 3412.5 4984.9 5295.0 5629.4 5644.5 5666.1 5733.4 5787.5 5788.0 5799.2 5822.9 5224.2 5252.6 5271.3 5318.3 5326.3 5323.8 5338.8 5355.5 5363.3 5367.0 5341.5 5343.5 5344.1 5347.9 5393.7 5143.4 5198.7 5254.4 5300.9 5338.6 5162.7 5251.5 5261.7 5268.2 5298.0 5669.3 5770.3 5784.0 5808.9 5815.7 5986.4 6143.1 6143.6 6172.3 6199.5 6117.2 6184.9 6203.4 6245.3 6261.7 5845.4 5866.9 5868.6 5873.6 5882.2 6170.3 6303.8 6309.1 6333.8 6334.7 5107.0 5477.4 6051.0 6117.3 6134.3 4578.9 6164.6 6183.4 6215.9 6252.6 5737.2 6207.8 6238.0 6277.7 6291.1 5363.5 5864.3 6055.2 6276.5 6296.8 3820.2 4002.3 4206.5 4800.4 4999.5 3693.9 3927.6 4344.6 4376.2 4428.6 4775.8 5763.7 5939.9 5939.9 5983.7 6153.8 6197.1 6214.1 6234.1 6274.0 5909.3 5945.1 5959.3 5962.8 5988.5 5406.4 5520.0 5537.7 5539.3 5540.0 6209.3 6209.9 6263.6 6293.9 6369.5 6204.8 6258.8 6277.6 6283.7 6289.8 6277.3 6300.1 6336.5 6384.6 6389.7 6074.8 6075.2 6112.2 6137.3 6161.7 5789.2 6260.0 6267.2 6281.6 6290.3 6166.2 6174.1 6178.7 6183.7 6202.8 5959.4 6038.4 6054.0 6058.3 6093.6 5820.1 5934.4 5967.4 5981.3 5991.5 6112.7 6121.3 6122.3 6127.4 6130.5 5900.1 5908.3 5946.2 5965.2 5966.6 04:16:20 INFO - PROCESS | 1644 | 04:16:20 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:72149.24 Median:39103.80 stddev:161359.45 (412.6%) stddev-sans-first:161722.35 04:16:20 INFO - PROCESS | 1644 | Values: 467.9 508.6 520.8 530.8 540.3 28800.3 38594.6 38646.4 38691.6 38783.2 39370.8 39739.8 40022.2 40083.0 40251.2 9036.1 9172.8 9192.0 9204.9 9206.6 9131.6 9175.9 9220.8 9232.4 9239.2 9174.2 9211.1 9222.1 9228.5 9230.7 7829.9 7981.7 8642.2 9218.0 9237.8 9186.6 9284.5 9299.5 9307.3 9308.2 38117.8 38408.2 38595.6 38601.8 38614.4 2837.4 3266.9 3349.2 3375.9 3413.5 28569.6 29418.6 29627.7 29644.0 30033.4 2727.9 3142.9 3152.1 3176.2 3178.8 504537.0 512828.0 512916.0 514333.0 514449.4 56694.0 57000.4 57035.7 57039.1 57193.0 58488.4 59334.4 59368.7 59520.4 59614.7 14651.5 14854.6 14885.6 14888.0 14942.0 11419.7 11459.0 11466.1 11473.9 11477.9 14772.0 15215.5 15279.5 15354.6 15521.6 3125.6 5865.9 6104.4 6122.1 6141.5 873.0 959.9 981.4 1063.0 1156.6 29778.6 29792.0 29821.0 30159.5 30249.0 1004.5 1017.1 1023.5 1096.9 1101.6 14427.9 14705.9 14876.3 14922.1 14926.0 618896.0 620774.0 625054.0 626465.0 627416.0 52842.0 53127.2 53212.2 53223.7 53278.5 1348.1 1350.2 1368.9 1372.6 1372.7 27564.6 28260.4 28938.0 29478.7 30716.0 51703.0 52960.0 53052.0 54266.0 56923.7 657264.0 660648.0 662278.0 663669.0 665604.0 40040.0 40580.6 40899.8 40987.6 41047.8 44839.6 45094.0 45313.3 45683.3 45784.3 36610.5 37049.1 37754.8 38836.8 39475.8 36236.8 37738.6 37799.4 38545.0 39545.0 35555.6 36052.0 36643.6 36814.7 36848.4 29413.9 31912.2 34156.6 34159.8 34427.1 22598.2 24672.0 25193.2 25346.6 25493.2 04:16:20 INFO - PROCESS | 1644 | 04:16:20 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:33094.28 Median:13622.17 stddev:94254.96 (691.9%) stddev-sans-first:94487.18 04:16:20 INFO - PROCESS | 1644 | Values: 287.0 319.0 361.9 372.3 389.7 18659.8 18966.4 20313.6 20518.4 20580.2 18647.5 18838.3 19375.6 19629.1 19701.3 5712.4 5994.8 5999.1 6000.7 6014.6 5700.8 5825.7 5857.6 5888.6 5889.2 5741.5 5805.7 5823.6 5848.5 5857.6 5543.2 5844.8 5848.9 5863.5 5875.1 4463.9 4792.6 5141.0 5154.8 5168.0 24390.0 24701.3 24749.4 25057.0 25136.0 2414.1 2495.0 2700.0 2854.9 3015.0 14940.4 14981.7 15059.2 15328.6 15657.2 1787.5 1815.9 1836.3 1892.7 1896.6 98054.0 100869.0 101152.0 101343.0 103127.0 10503.3 10514.9 10933.5 10955.5 11125.7 13254.3 13409.2 13502.3 13505.3 13520.1 7808.7 8040.8 8135.2 8226.0 8248.3 7972.4 7990.3 8055.2 8078.9 8108.3 6449.1 6643.6 6658.6 6660.8 6665.6 1827.0 1835.6 1855.4 1869.9 1898.2 827.9 879.9 890.4 915.6 922.1 17815.0 19579.7 21859.3 21990.3 22082.4 950.9 957.0 961.9 982.5 1072.7 10953.7 11697.2 11758.0 11763.5 11783.4 522760.0 567036.0 570687.0 589375.0 589688.0 106983.5 106987.0 112459.0 113038.4 114338.0 878.9 976.7 1002.9 1008.1 1008.8 18297.0 19138.4 19517.6 19578.6 19618.6 50949.2 51096.7 51685.7 51861.7 51965.1 69859.7 71779.6 73295.0 73856.9 74084.0 7636.5 7760.2 7800.5 8036.9 8302.7 12432.5 13018.9 13102.5 13118.0 13354.2 10875.0 11228.5 11298.8 11519.2 11747.7 10809.9 11090.7 11310.9 11415.1 11506.4 10522.7 11165.7 11317.3 11663.4 11920.4 5439.4 6577.9 6833.8 6982.9 7405.6 8879.6 11217.7 11226.7 11309.8 11566.2 04:16:20 INFO - PROCESS | 1644 | 04:16:20 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5304.65 Median:5058.65 stddev:2921.06 (57.7%) stddev-sans-first:2914.62 04:16:20 INFO - PROCESS | 1644 | Values: 1413.1 1433.8 1611.9 1725.6 1773.7 7351.9 7605.6 7856.1 8331.5 8340.0 7774.5 8046.4 8055.8 8110.7 8155.0 1765.4 2107.5 2255.3 2266.6 2304.9 2279.4 2304.7 2329.9 2342.0 2344.8 2286.4 2289.0 2309.3 2322.1 2325.1 2269.2 2281.0 2305.4 2306.3 2314.4 2252.3 2270.5 2278.0 2281.6 2282.2 8148.0 8191.7 8197.3 8266.3 8287.5 4825.9 4996.3 5002.5 5003.4 5008.9 3008.8 3271.4 3472.6 3479.9 3485.5 1029.7 1049.4 1075.2 1084.7 1107.0 9437.9 10618.7 10666.2 10675.8 10749.1 3761.9 4346.8 4559.5 4579.3 4588.8 9061.0 9107.6 9142.5 9146.0 9187.4 5051.9 5056.9 5060.4 5065.8 5084.4 3735.8 4151.0 4622.2 4644.9 4652.4 3716.3 3743.1 3851.9 3854.4 3857.6 1233.2 1252.2 1259.3 1265.2 1272.0 789.5 883.7 892.6 912.2 916.2 5477.2 5479.6 5489.9 5644.0 5724.1 417.3 422.5 434.9 440.8 441.9 3252.8 3546.9 3638.4 3676.0 3777.5 9396.9 9763.9 9898.4 9907.5 9943.9 9872.3 9897.4 9908.4 9966.4 9979.5 6751.9 7284.6 7444.7 7522.8 7724.9 5388.6 5418.6 5470.2 5595.6 5642.9 9022.4 9039.5 9196.5 9213.0 9265.8 8856.6 9163.4 9196.7 9209.2 9339.1 6987.5 7227.6 7550.8 7672.3 7685.9 7420.9 7565.1 7682.2 7754.0 7754.4 6882.0 6935.3 7017.2 7023.3 7045.2 6728.1 6786.3 6840.0 6855.1 6925.3 6413.8 6424.5 6430.2 6442.5 6489.4 4648.9 4655.9 4716.4 4745.9 4810.5 4721.1 4736.3 4740.5 4803.9 4870.5 04:16:20 INFO - PROCESS | 1644 | 04:16:20 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4602.96 Median:5238.88 stddev:1476.54 (28.2%) stddev-sans-first:1444.20 04:16:20 INFO - PROCESS | 1644 | Values: 256.9 297.1 300.7 304.5 309.0 5482.2 5523.0 5544.3 5548.9 5554.6 4617.4 5117.8 5419.9 5423.8 5450.6 3357.6 3932.7 3991.4 4022.2 4068.1 3390.3 4110.8 4129.8 4134.5 4144.0 4080.8 4102.3 4125.5 4147.0 4151.6 3145.6 3452.5 4062.3 4091.0 4165.2 3480.5 3481.3 3482.5 3497.1 3497.5 4829.2 5264.8 5279.7 5328.6 5356.2 3979.3 3997.7 4332.8 4635.1 4669.5 4498.9 4595.3 5706.9 5741.0 5746.7 3849.3 3871.6 3872.0 3877.2 3886.0 5160.8 5581.2 5728.5 6103.6 6115.5 3150.6 3795.5 3864.4 3870.4 3922.4 3311.8 3937.3 3946.5 3950.0 3974.8 3907.5 3928.5 4599.7 4622.8 4655.3 5011.4 5285.4 5829.3 5872.7 5879.7 1109.4 1204.4 1256.7 1280.9 1288.9 664.9 823.7 851.0 863.4 867.1 4361.8 5007.0 5057.8 5226.5 5513.3 5589.9 5866.8 5914.8 5989.6 5996.8 4965.1 5205.9 5391.9 5452.3 5496.8 2959.0 2994.8 2998.3 3007.8 3008.3 5090.2 5842.0 5940.7 5968.7 5969.6 5298.3 5746.7 6161.6 6187.7 6220.8 5378.8 5481.5 5540.8 6260.7 6288.4 4560.0 5098.3 5168.0 5251.2 5290.9 5432.9 5923.9 5988.6 5993.6 5996.4 5276.6 6317.7 6322.8 6335.9 6364.6 3283.9 3463.7 3801.2 3914.6 3950.0 5650.4 5992.8 6015.9 6048.3 6055.3 5891.6 5908.7 5908.8 5911.7 5917.5 5128.6 5594.3 5751.6 5882.2 5886.4 4950.5 5600.9 5733.5 5738.9 5756.5 5185.5 5407.2 5728.9 5817.7 5833.2 4751.2 5408.8 5655.9 5666.2 5709.4 04:16:20 INFO - PROCESS | 1644 | 04:16:20 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:18851.44 Median:51512.39 stddev:26435.08 (51.3%) stddev-sans-first:26473.67 04:16:20 INFO - PROCESS | 1644 | Values: 589.8 701.7 739.8 760.0 796.9 13871.5 14437.6 14459.6 14537.0 14781.3 13263.6 13300.7 13376.2 13384.4 13473.0 632.1 655.1 791.6 809.5 819.1 681.8 703.8 817.6 831.2 832.1 856.2 869.7 875.9 876.0 878.4 859.8 870.4 874.5 874.7 876.6 626.8 650.4 652.1 652.8 654.6 11623.4 13520.5 13550.2 13567.5 13602.7 13019.9 13287.3 13325.4 13344.9 13442.4 11099.7 11181.6 11264.0 11328.2 11717.5 3588.2 3652.1 3676.2 3693.9 3708.7 73915.6 75331.0 84106.6 84597.6 85477.6 9966.6 10388.1 10413.6 10415.5 10467.4 19604.3 19628.4 19796.7 19806.5 19879.4 7312.3 7393.2 7545.3 7662.3 7743.4 4212.0 4273.3 4280.3 4299.2 4406.2 2924.7 3294.8 3372.7 3403.6 3429.0 632.7 635.7 639.0 648.6 648.8 632.9 633.6 649.7 652.5 654.5 8670.9 8707.8 8908.9 9005.1 9067.6 621.8 640.6 664.7 671.9 674.6 3733.5 4211.9 4405.5 4430.5 4466.1 89582.6 92096.7 92472.0 94628.5 114979.0 59101.7 62554.7 77585.0 80682.0 83585.0 56398.2 75095.0 79806.0 84066.2 84819.0 9122.6 9424.9 9505.5 9545.4 9599.1 51015.0 52009.8 52549.7 53887.2 53910.5 63418.0 68034.7 71863.0 74545.6 77635.2 10228.9 10420.3 11990.4 12029.8 12084.3 13512.2 13558.5 13582.4 13767.5 15020.3 12172.1 12633.2 13021.3 13157.3 13972.4 10798.8 11188.6 12517.4 13138.3 13495.7 12455.4 12842.2 12869.8 12940.4 13565.8 10567.3 10583.6 10706.8 11085.7 11135.0 10786.4 10855.4 11050.1 11051.2 11110.1 04:16:20 INFO - PROCESS | 1644 | -------- Summary: end -------- 04:16:20 INFO - PROCESS | 1644 | 04:16:20 INFO - TEST-INFO | 1644: exit 0 04:16:20 INFO - TEST-OK | dromaeo_css | took 1118275ms 04:16:20 INFO - TEST-START | kraken 04:16:20 INFO - operating with platform_type : mac_ 04:16:20 INFO - Initialising browser for kraken test... 04:16:20 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmpcbswth/profile http://localhost:49253/getInfo.html) 04:16:23 INFO - PROCESS | 1655 | 2016-12-01 04:16:23.457 plugin-container[1657:10484] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b37, name = 'com.apple.tsm.portname' 04:16:23 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:16:23 INFO - PROCESS | 1655 | 2016-12-01 04:16:23.459 plugin-container[1657:10484] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e1f, name = 'com.apple.CFPasteboardClient' 04:16:23 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:16:23 INFO - PROCESS | 1655 | 2016-12-01 04:16:23.459 plugin-container[1657:10484] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:16:23 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 04:16:23 INFO - PROCESS | 1655 | colorDepth:24 04:16:23 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 04:16:23 INFO - PROCESS | 1655 | __metrics 04:16:23 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 04:16:24 INFO - TEST-INFO | 1655: exit 0 04:16:24 INFO - Browser initialized. 04:16:24 INFO - Running cycle 1/1 for kraken test... 04:16:24 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmpcbswth/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:16:27 INFO - PROCESS | 1658 | 2016-12-01 04:16:27.136 plugin-container[1659:10609] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 04:16:27 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:16:27 INFO - PROCESS | 1658 | 2016-12-01 04:16:27.137 plugin-container[1659:10609] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 04:16:27 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:16:27 INFO - PROCESS | 1658 | 2016-12-01 04:16:27.137 plugin-container[1659:10609] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:16:36 INFO - PROCESS | 1658 | RSS: Main: 229244928 04:16:36 INFO - PROCESS | 1658 | 04:16:39 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49253/tests/kraken/driver.html?testName=audio-beat-detection) 04:16:40 INFO - PROCESS | 1658 | RSS: Main: 241430528 04:16:40 INFO - PROCESS | 1658 | 04:16:43 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49253/tests/kraken/driver.html?testName=audio-dft) 04:16:43 INFO - PROCESS | 1658 | RSS: Main: 249004032 04:16:43 INFO - PROCESS | 1658 | 04:16:46 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49253/tests/kraken/driver.html?testName=audio-fft) 04:16:47 INFO - PROCESS | 1658 | RSS: Main: 252833792 04:16:47 INFO - PROCESS | 1658 | 04:16:49 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49253/tests/kraken/driver.html?testName=audio-oscillator) 04:16:50 INFO - PROCESS | 1658 | RSS: Main: 254103552 04:16:50 INFO - PROCESS | 1658 | 04:16:52 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49253/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:16:52 INFO - PROCESS | 1658 | RSS: Main: 254156800 04:16:52 INFO - PROCESS | 1658 | 04:16:55 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49253/tests/kraken/driver.html?testName=imaging-darkroom) 04:16:56 INFO - PROCESS | 1658 | RSS: Main: 258641920 04:16:56 INFO - PROCESS | 1658 | 04:16:59 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49253/tests/kraken/driver.html?testName=imaging-desaturate) 04:17:00 INFO - PROCESS | 1658 | RSS: Main: 257703936 04:17:00 INFO - PROCESS | 1658 | 04:17:02 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49253/tests/kraken/driver.html?testName=json-parse-financial) 04:17:03 INFO - PROCESS | 1658 | RSS: Main: 258215936 04:17:03 INFO - PROCESS | 1658 | 04:17:05 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49253/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:17:05 INFO - PROCESS | 1658 | RSS: Main: 257028096 04:17:05 INFO - PROCESS | 1658 | 04:17:07 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:17:08 INFO - PROCESS | 1658 | RSS: Main: 257363968 04:17:08 INFO - PROCESS | 1658 | 04:17:10 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:17:11 INFO - PROCESS | 1658 | RSS: Main: 256864256 04:17:11 INFO - PROCESS | 1658 | 04:17:14 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:17:14 INFO - PROCESS | 1658 | RSS: Main: 257056768 04:17:14 INFO - PROCESS | 1658 | 04:17:17 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:17:18 INFO - PROCESS | 1658 | RSS: Main: 257912832 04:17:18 INFO - PROCESS | 1658 | 04:17:20 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49253/tests/kraken/driver.html?testName=ai-astar) 04:17:20 INFO - PROCESS | 1658 | RSS: Main: 259473408 04:17:20 INFO - PROCESS | 1658 | 04:17:20 INFO - PROCESS | 1658 | __start_tp_report 04:17:20 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 04:17:20 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 04:17:20 INFO - PROCESS | 1658 | |i|pagename|runs| 04:17:20 INFO - PROCESS | 1658 | |0;ai-astar;116;122;118;117;119;117;117;117;117;119 04:17:20 INFO - PROCESS | 1658 | |1;audio-beat-detection;145;139;138;134;131;146;145;106;171;145 04:17:20 INFO - PROCESS | 1658 | |2;audio-dft;194;194;196;200;193;196;195;193;196;196 04:17:20 INFO - PROCESS | 1658 | |3;audio-fft;88;58;59;84;59;58;58;57;76;74 04:17:20 INFO - PROCESS | 1658 | |4;audio-oscillator;83;69;90;72;70;77;73;57;60;86 04:17:20 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;89;102;102;106;135;132;101;96;135;107 04:17:20 INFO - PROCESS | 1658 | |6;imaging-darkroom;147;94;95;95;103;95;102;141;95;123 04:17:20 INFO - PROCESS | 1658 | |7;imaging-desaturate;88;89;86;93;89;89;93;91;88;100 04:17:20 INFO - PROCESS | 1658 | |8;json-parse-financial;47;51;87;90;90;60;56;78;52;56 04:17:20 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;68;68;80;78;75;71;73;71;71;99 04:17:20 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;104;105;79;84;86;85;84;84;85;85 04:17:20 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;147;149;146;150;163;151;148;153;212;162 04:17:20 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;164;239;169;170;170;172;182;169;168;227 04:17:20 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;71;70;68;69;69;67;73;66;91;91 04:17:20 INFO - PROCESS | 1658 | __end_tp_report 04:17:20 INFO - PROCESS | 1658 | __start_cc_report 04:17:20 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1002 04:17:20 INFO - PROCESS | 1658 | __end_cc_report 04:17:20 INFO - PROCESS | 1658 | __startTimestamp1480594640529__endTimestamp 04:17:20 INFO - PROCESS | 1658 | 04:17:20 INFO - PROCESS | 1658 | ------- Summary: start ------- 04:17:20 INFO - PROCESS | 1658 | Number of tests: 14 04:17:20 INFO - PROCESS | 1658 | 04:17:20 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:117.90 Median:117.50 stddev:1.73 (1.5%) stddev-sans-first:1.69 04:17:20 INFO - PROCESS | 1658 | Values: 116.0 122.0 118.0 117.0 119.0 117.0 117.0 117.0 117.0 119.0 04:17:20 INFO - PROCESS | 1658 | 04:17:20 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:140.00 Median:145.00 stddev:16.16 (11.1%) stddev-sans-first:17.04 04:17:20 INFO - PROCESS | 1658 | Values: 145.0 139.0 138.0 134.0 131.0 146.0 145.0 106.0 171.0 145.0 04:17:20 INFO - PROCESS | 1658 | 04:17:20 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:195.30 Median:196.00 stddev:2.06 (1.0%) stddev-sans-first:2.13 04:17:20 INFO - PROCESS | 1658 | Values: 194.0 194.0 196.0 200.0 193.0 196.0 195.0 193.0 196.0 196.0 04:17:20 INFO - PROCESS | 1658 | 04:17:20 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:67.10 Median:66.50 stddev:12.16 (18.3%) stddev-sans-first:10.28 04:17:20 INFO - PROCESS | 1658 | Values: 88.0 58.0 59.0 84.0 59.0 58.0 58.0 57.0 76.0 74.0 04:17:20 INFO - PROCESS | 1658 | 04:17:20 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:73.70 Median:75.00 stddev:10.65 (14.2%) stddev-sans-first:10.75 04:17:20 INFO - PROCESS | 1658 | Values: 83.0 69.0 90.0 72.0 70.0 77.0 73.0 57.0 60.0 86.0 04:17:20 INFO - PROCESS | 1658 | 04:17:20 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:110.50 Median:133.50 stddev:17.00 (12.7%) stddev-sans-first:16.16 04:17:20 INFO - PROCESS | 1658 | Values: 89.0 102.0 102.0 106.0 135.0 132.0 101.0 96.0 135.0 107.0 04:17:20 INFO - PROCESS | 1658 | 04:17:20 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:109.00 Median:94.50 stddev:20.43 (21.6%) stddev-sans-first:16.41 04:17:20 INFO - PROCESS | 1658 | Values: 147.0 94.0 95.0 95.0 103.0 95.0 102.0 141.0 95.0 123.0 04:17:20 INFO - PROCESS | 1658 | 04:17:20 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:90.60 Median:89.00 stddev:3.98 (4.5%) stddev-sans-first:4.11 04:17:20 INFO - PROCESS | 1658 | Values: 88.0 89.0 86.0 93.0 89.0 89.0 93.0 91.0 88.0 100.0 04:17:20 INFO - PROCESS | 1658 | 04:17:20 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:66.70 Median:69.00 stddev:17.48 (25.3%) stddev-sans-first:17.03 04:17:20 INFO - PROCESS | 1658 | Values: 47.0 51.0 87.0 90.0 90.0 60.0 56.0 78.0 52.0 56.0 04:17:20 INFO - PROCESS | 1658 | 04:17:20 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:75.40 Median:74.00 stddev:9.18 (12.4%) stddev-sans-first:9.34 04:17:20 INFO - PROCESS | 1658 | Values: 68.0 68.0 80.0 78.0 75.0 71.0 73.0 71.0 71.0 99.0 04:17:20 INFO - PROCESS | 1658 | 04:17:20 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:84.50 stddev:8.85 (10.5%) stddev-sans-first:7.28 04:17:20 INFO - PROCESS | 1658 | Values: 104.0 105.0 79.0 84.0 86.0 85.0 84.0 84.0 85.0 85.0 04:17:20 INFO - PROCESS | 1658 | 04:17:20 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:158.10 Median:152.00 stddev:19.84 (13.0%) stddev-sans-first:20.63 04:17:20 INFO - PROCESS | 1658 | Values: 147.0 149.0 146.0 150.0 163.0 151.0 148.0 153.0 212.0 162.0 04:17:20 INFO - PROCESS | 1658 | 04:17:20 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.00 Median:171.00 stddev:26.89 (15.7%) stddev-sans-first:27.63 04:17:20 INFO - PROCESS | 1658 | Values: 164.0 239.0 169.0 170.0 170.0 172.0 182.0 169.0 168.0 227.0 04:17:20 INFO - PROCESS | 1658 | 04:17:20 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.50 Median:70.50 stddev:9.43 (13.4%) stddev-sans-first:9.96 04:17:20 INFO - PROCESS | 1658 | Values: 71.0 70.0 68.0 69.0 69.0 67.0 73.0 66.0 91.0 91.0 04:17:20 INFO - PROCESS | 1658 | -------- Summary: end -------- 04:17:20 INFO - PROCESS | 1658 | 04:17:21 INFO - TEST-INFO | 1658: exit 0 04:17:21 INFO - TEST-OK | kraken | took 60874ms 04:17:21 INFO - SUITE-END | took 1179s 04:17:21 INFO - Completed test suite (00:19:39) 04:17:21 INFO - javascript benchmark 04:17:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7105.6387103524885, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3103.1156120505652, 3377.105067157204, 3409.045467269164, 3410.686885549063, 3412.522246885435, 4984.850454486362, 5295.020421222077, 5629.35262444819, 5644.520215781658, 5666.093425051992, 5733.426657334265, 5787.47912687858, 5788.0, 5799.217105690741, 5822.883542329151, 5224.190250511177, 5252.579793616521, 5271.262023316739, 5318.25544423781, 5326.3075800145925, 5323.840284791462, 5338.813141540047, 5355.4912283333215, 5363.329583802025, 5367.007103685831, 5341.4658534146465, 5343.519083282504, 5344.0915044442345, 5347.919781203285, 5393.73031348433, 5143.357080364935, 5198.662086964335, 5254.395744489356, 5300.893982120336, 5338.599605029619, 5162.664426812245, 5251.501107394792, 5261.710605916673, 5268.209768534751, 5298.0, 5669.348024977139, 5770.307563092414, 5783.987802134609, 5808.941910580925, 5815.709214539298, 5986.401359864022, 6143.0820360328935, 6143.573288550997, 6172.290186628506, 6199.504039676815, 6117.248930028882, 6184.907226391608, 6203.410675985774, 6245.281792593864, 6261.655608941506, 5845.415458454163, 5866.911996320058, 5868.618539794942, 5873.618214816023, 5882.205902203191, 6170.290416602058, 6303.842403939892, 6309.148264984215, 6333.809985700436, 6334.683265836782, 5107.004134193797, 5477.397486276506, 6051.031834906482, 6117.296510901214, 6134.294556126102, 4578.88552786183, 6164.599301045418, 6183.412575805291, 6215.906761398583, 6252.5935814171935, 5737.225474560964, 6207.81376558704, 6238.0, 6277.686115694197, 6291.056341548804, 5363.517283444508, 5864.266966629171, 6055.152278680988, 6276.529260305495, 6296.779612713533, 3820.1977584707524, 4002.3396139636634, 4206.453161089039, 4800.3759511263315, 4999.52504512078, 3693.8707145249787, 3927.5876033016693, 4344.56554344563, 4376.212281789309, 4428.557144285545, 4775.761211939389, 5763.682997435181, 5939.851503712442, 5939.910901336483, 5983.700814959235, 6153.784617538454, 6197.1324014637985, 6214.130021796952, 6234.064890266405, 6274.0, 5909.3204281507315, 5945.078512830518, 5959.314678811992, 5962.791302304398, 5988.520918326523, 5406.405295417501, 5519.972400137974, 5537.723113844291, 5539.279893613804, 5540.0, 6209.316975132641, 6209.906851397142, 6263.592866463665, 6293.874122517615, 6369.490440764635, 6204.81385558434, 6258.843528911813, 6277.591956522994, 6283.748650053946, 6289.811305660837, 6277.309495955533, 6300.086487459201, 6336.461400780985, 6384.553081284265, 6389.7124629390855, 6074.757009719562, 6075.2405949256345, 6112.229929638781, 6137.315698286874, 6161.691915404302, 5789.189513468202, 6260.029695397221, 6267.1539342188225, 6281.623102613858, 6290.2766181889665, 6166.229221347332, 6174.135621013152, 6178.721957511832, 6183.690815459299, 6202.844928876814, 5959.374265702038, 6038.426349496791, 6053.97082495968, 6058.333583305921, 6093.6039157454625, 5820.097884827858, 5934.43622855828, 5967.433093856077, 5981.312149102735, 5991.520678345635, 6112.694365281629, 6121.32665406796, 6122.306673174842, 6127.448529632355, 6130.540209484396, 5900.144479050427, 5908.291005079418, 5946.1972633693895, 5965.2245208124405, 5966.612170208922], "value": 5661.844857297248, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.85730352242575, 508.59058457941364, 520.8333333333334, 530.8434011966469, 540.2706346432315, 28800.27999300016, 38594.614053859485, 38646.420303695464, 38691.61308386915, 38783.224335513274, 39370.80314598423, 39739.8078057658, 40022.19955600886, 40082.997925051815, 40251.19497610053, 9036.141566551167, 9172.816543669123, 9191.954040229823, 9204.861927071099, 9206.631734730603, 9131.58907849145, 9175.908240917606, 9220.769480763001, 9232.446053236794, 9239.214666753334, 9174.220191283748, 9211.124943130391, 9222.123898229658, 9228.492432916188, 9230.67692630758, 7829.882551761728, 7981.680732770682, 8642.178992995718, 9218.0, 9237.815243695155, 9186.586603602855, 9284.53577321138, 9299.48852813095, 9307.255419566418, 9308.208802251816, 38117.80941095304, 38408.231835363134, 38595.61404385948, 38601.806990965146, 38614.42078368827, 2837.3757773289844, 3266.934661306784, 3349.1627093226693, 3375.949360759566, 3413.4709120086427, 28569.580640507054, 29418.558721619193, 29627.70372296271, 29644.0, 30033.399332013454, 2727.931801704973, 3142.858571364294, 3152.133163380066, 3176.2218256527067, 3178.761592880543, 504537.0, 512828.0, 512916.0, 514333.0, 514449.42775286257, 56694.0, 57000.42999569951, 57035.71482142563, 57039.14441283384, 57193.0, 58488.41511584915, 59334.406655932886, 59368.70315648394, 59520.40479595235, 59614.701926490954, 14651.487197948021, 14854.554363369116, 14885.553433397015, 14888.0, 14942.0, 11419.714507137222, 11459.0, 11466.140039497071, 11473.942630286796, 11477.885221147682, 14772.0, 15215.543533693786, 15279.465218717514, 15354.616134596501, 15521.611959700873, 3125.6092988376454, 5865.853353666107, 6104.389561043949, 6122.142899994004, 6141.508679305645, 872.9694460693972, 959.9424034557809, 981.4454833019321, 1062.984055239172, 1156.612534800851, 29778.55332170019, 29791.957281495044, 29821.0, 30159.547606785916, 30249.0, 1004.4726518577805, 1017.0846238385512, 1023.4729114506035, 1096.8793432722393, 1101.614434947778, 14427.92786036063, 14705.926470367795, 14876.330565124596, 14922.104673719612, 14926.0, 618896.0, 620774.0, 625054.0, 626465.0, 627416.0, 52842.0, 53127.20309195365, 53212.201816972774, 53223.73388133112, 53278.46721532813, 1348.0967751606447, 1350.1763924006348, 1368.8699573540498, 1372.6362513933816, 1372.6774208061097, 27564.586531201647, 28260.434791303647, 28938.0, 29478.705212947596, 30716.0, 51703.0, 52960.0, 53052.0, 54266.0, 56923.71538142283, 657264.0, 660648.0, 662278.0, 663669.0, 665604.0, 40040.0, 40580.59419405768, 40899.7955010223, 40987.59012409838, 41047.794761026, 44839.55160448484, 45094.0, 45313.320300194864, 45683.31475027944, 45784.313235302165, 36610.45084323791, 37049.07377315481, 37754.8112259437, 38836.805815970736, 39475.81572552828, 36236.81881590575, 37738.62261377461, 37799.433008505446, 38545.0, 39545.01137471471, 35555.64444355523, 36052.0, 36643.63356366402, 36814.71148509789, 36848.44727329039, 29413.85293073521, 31912.202194945312, 34156.609037368136, 34159.829200853834, 34427.13932151716, 22598.19214462806, 24672.0, 25193.244202673948, 25346.619800702636, 25493.235202943943], "value": 19379.826910417352, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.9683487861139, 318.96650851660587, 361.91675914539655, 372.33538134430046, 389.7232964595139, 18659.813401865977, 18966.431007069776, 20313.59372812543, 20518.38444846657, 20580.176792928265, 18647.533811654714, 18838.340658076937, 19375.612487750237, 19629.11668974896, 19701.31045413411, 5712.428757124286, 5994.820155395323, 5999.070144127667, 6000.669963152025, 6014.578979471439, 5700.771969121251, 5825.650460972355, 5857.6485410875275, 5888.558358123137, 5889.175515427822, 5741.540676745871, 5805.680687562182, 5823.621464604808, 5848.5321174305955, 5857.589968702171, 5543.168524721304, 5844.824655260328, 5848.941510584882, 5863.530917526609, 5875.089361148986, 4463.910721785579, 4792.640551958617, 5140.974295128501, 5154.819581314636, 5168.0, 24390.0, 24701.258962230982, 24749.381265468328, 25057.0, 25135.99456021757, 2414.0705828256073, 2494.962575561368, 2700.0, 2854.9286267843263, 3014.9849250753823, 14940.402383904631, 14981.700365992729, 15059.247037648074, 15328.612944783523, 15657.217139143111, 1787.4637608717333, 1815.9001254930974, 1836.2563161919445, 1892.6877065284211, 1896.5637903282322, 98054.0, 100869.0, 101152.0, 101342.97314053752, 103127.0, 10503.317284376491, 10514.947425262824, 10933.453327333602, 10955.452227388758, 11125.72185695348, 13254.271015094262, 13409.195448273134, 13502.324883755773, 13505.32473376347, 13520.12119212248, 7808.726694565663, 8040.798980025429, 8135.186481351818, 8225.971753530808, 8248.298894593907, 7972.36221102322, 7990.280874721187, 8055.154208808108, 8078.919210807934, 8108.310793582612, 6449.129367535416, 6643.634600096944, 6658.5671931324305, 6660.766873159416, 6665.600063996176, 1826.9543261418305, 1835.6236971420826, 1855.3784482198614, 1869.9439016829517, 1898.1742941820353, 827.9195649677109, 879.9340049496313, 890.3900827932887, 915.6428992692855, 922.0502882031518, 17815.0, 19579.706304405445, 21859.344219673436, 21990.340289791333, 22082.44793880166, 950.8811398575178, 956.9808603827885, 961.918236949853, 982.5480279071564, 1072.7050061233224, 10953.726156846142, 11697.23967942081, 11758.0, 11763.470643821049, 11783.351915644722, 522760.0, 567036.0, 570687.0, 589375.0, 589688.0, 106983.46508267408, 106987.0, 112459.0, 113038.43480782544, 114338.0, 878.892595329889, 976.7069879036261, 1002.8746406699308, 1008.1330056151704, 1008.8284991551307, 18296.993665348535, 19138.425847224604, 19517.609647807247, 19578.60842783165, 19618.60762784765, 50949.23576146435, 51096.744516277184, 51685.7415712919, 51861.70345741387, 51965.05245120949, 69859.65070174617, 71779.64110179416, 73295.0, 73856.89214661892, 74084.0, 7636.541807491568, 7760.185180556186, 7800.492967957065, 8036.879446808186, 8302.667893284442, 12432.502699891904, 13018.934905325414, 13102.475900963855, 13117.95056395467, 13354.198748075147, 10875.02124808771, 11228.494717737885, 11298.83051754208, 11519.19365644397, 11747.706307342043, 10809.945950270197, 11090.722731931766, 11310.92546208109, 11415.086793056369, 11506.367149806842, 10522.684664416947, 11165.665030049111, 11317.320960742381, 11663.358515281741, 11920.403979801149, 5439.4288599696465, 6577.868442631025, 6833.794986150423, 6982.895256571257, 7405.5926924019695, 8879.556022198993, 11217.66347009591, 11226.719332016764, 11309.830352544883, 11566.248193867372], "value": 9513.209177247063, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1413.0885578801672, 1433.81360423145, 1611.8549330560254, 1725.6289897672013, 1773.716205407135, 7351.889721654179, 7605.619719014041, 7856.057273127226, 8331.458455200407, 8340.0, 7774.494657847236, 8046.396520260974, 8055.798605034877, 8110.675572977088, 8155.0213974323015, 1765.3732924811668, 2107.473131717071, 2255.289583781112, 2266.580682573729, 2304.942376440594, 2279.384566167142, 2304.677345171677, 2329.9301020969397, 2342.0397636968846, 2344.765523447659, 2286.371247906822, 2288.965665515019, 2309.284121922201, 2322.082777302964, 2325.1396983116188, 2269.1830940861278, 2281.041962375798, 2305.446692793726, 2306.3196357074703, 2314.3982564533176, 2252.2680128958154, 2270.4664403865077, 2277.986296098237, 2281.6349384098467, 2282.155602427096, 8148.0, 8191.672333106669, 8197.303229225523, 8266.256036956642, 8287.461315014505, 4825.927611085836, 4996.300517927493, 5002.474740152305, 5003.399592048978, 5008.874778130539, 3008.7893847430687, 3271.362084393544, 3472.6353732858192, 3479.9478007829903, 3485.529453523767, 1029.7219750667352, 1049.3651340938698, 1075.1560025380038, 1084.69628504019, 1107.03687791621, 9437.858432123523, 10618.734531636694, 10666.200034997328, 10675.78648427035, 10749.086327662159, 3761.887143385703, 4346.7609281489185, 4559.475660299042, 4579.267317229227, 4588.77056147198, 9060.954695226614, 9107.635694572144, 9142.542872856331, 9145.954270228607, 9187.402818816756, 5051.898962020812, 5056.898862022739, 5060.3927528696795, 5065.797368105234, 5084.44071152173, 3735.7571757835653, 4151.0, 4622.19111655459, 4644.885227545432, 4652.441706995114, 3716.2567486502808, 3743.1016556026357, 3851.9229615407535, 3854.383298672199, 3857.61423857612, 1233.2292317301687, 1252.1673087396962, 1259.2822091407809, 1265.2408554867006, 1271.993640031794, 789.5223389849174, 883.7216276872764, 892.6429428228761, 912.2428384440898, 916.2074805293373, 5477.233187353773, 5479.589030822704, 5489.862753431116, 5644.0, 5724.112762521816, 417.2822744878804, 422.4824589877437, 434.8825817029384, 440.81706091972114, 441.9005723712139, 3252.7723059385617, 3546.9004608571427, 3638.381475149231, 3675.9816200918826, 3777.490038844776, 9396.90603093974, 9763.853542196874, 9898.406095634286, 9907.45508997012, 9943.85645650745, 9872.259580531489, 9897.35667181631, 9908.35595686278, 9966.402015878926, 9979.501024948724, 6751.93248067513, 7284.599347035964, 7444.739434119671, 7522.774316770506, 7724.884126738103, 5388.56891448691, 5418.620696551204, 5470.234167216672, 5595.608307418442, 5642.88714225705, 9022.413543119676, 9039.45763254207, 9196.540172991457, 9212.953935230282, 9265.768355791159, 8856.557172141496, 9163.404378715362, 9196.724098277062, 9209.2172165364, 9339.112784285482, 6987.475939304471, 7227.602481863548, 7550.7734767957045, 7672.347850432776, 7685.884711729441, 7420.888686669597, 7565.092188937363, 7682.154962953959, 7754.030746156731, 7754.379649628129, 6882.0, 6935.2717964615, 7017.193022802443, 7023.262557431601, 7045.225025247116, 6728.125343705287, 6786.253512113763, 6840.0, 6855.143107111611, 6925.27284635106, 6413.775517856852, 6424.518161138027, 6430.196225471816, 6442.484601231984, 6489.415952564292, 4648.930266045944, 4655.86032419028, 4716.410448693913, 4745.860993361499, 4810.494898035656, 4721.102990431807, 4736.313234580897, 4740.478547359857, 4803.903921921612, 4870.464248932685], "value": 4237.50707115829, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.874131675479, 297.05092230324095, 300.6857833563927, 304.53102222577223, 309.0358082781721, 5482.177673349, 5523.0, 5544.251526043983, 5548.944510554898, 5554.611177217596, 4617.353570500132, 5117.848484091087, 5419.918701219485, 5423.83728488145, 5450.618456708032, 3357.5803024621923, 3932.665723413513, 3991.4012898065234, 4022.1754540319166, 4068.0643452006057, 3390.3219356128748, 4110.835566577333, 4129.834806607732, 4134.545200027995, 4143.979280103596, 4080.8367665293354, 4102.282100632392, 4125.484314460692, 4146.9792651036705, 4151.605597468236, 3145.5596216529475, 3452.46486794546, 4062.2890994076215, 4091.018155642654, 4165.1669666066755, 3480.5127282180515, 3481.3037392521596, 3482.512448257246, 3497.1431999160113, 3497.545319108525, 4829.203181475053, 5264.763085661154, 5279.70961597112, 5328.573714102862, 5356.223347614574, 3979.263836190314, 3997.700172487075, 4332.826686932551, 4635.122775276019, 4669.509701481329, 4498.932516012263, 4595.333676616872, 5706.885862282773, 5740.9712951435395, 5746.712664366807, 3849.326367885637, 3871.632194941476, 3871.9806400968096, 3877.185790983897, 3886.0, 5160.838811267472, 5581.2186293918885, 5728.541716662657, 6103.572749907508, 6115.510759139258, 3150.5904232450093, 3795.525559305087, 3864.3623802072907, 3870.4000879863665, 3922.3724204127775, 3311.8243023726714, 3937.2912875682077, 3946.5461471931094, 3950.0, 3974.8012599369918, 3907.531096268466, 3928.489296391451, 4599.6771630326475, 4622.791974361162, 4655.255159174516, 5011.423686276052, 5285.365756109291, 5829.329627092854, 5872.706364681749, 5879.676617785977, 1109.4412350647192, 1204.4459548607563, 1256.6795467156, 1280.8527019392889, 1288.9291088990008, 664.867026594683, 823.6697733161021, 851.000074911976, 863.4430792139035, 867.137198487509, 4361.847335343247, 5006.973570418056, 5057.797688092435, 5226.529612334908, 5513.255710479114, 5589.944100558942, 5866.794662186803, 5914.822555323347, 5989.55078369124, 5996.760129594767, 4965.1311020571975, 5205.9219111713355, 5391.892162156735, 5452.263944367539, 5496.80761173357, 2959.0, 2994.7903646744517, 2998.295400580905, 3007.8195308281133, 3008.2629755709554, 5090.160123579642, 5841.970790146107, 5940.673183060027, 5968.671723055187, 5969.611975221596, 5298.310994769657, 5746.655200687972, 6161.630302181883, 6187.659678717624, 6220.813375598739, 5378.757955891916, 5481.479259470424, 5540.806071787468, 6260.718267677966, 6288.365025093417, 4559.977200114111, 5098.337216161875, 5167.9922415128685, 5251.238570407346, 5290.947090529045, 5432.859099589131, 5923.851903702442, 5988.610740301867, 5993.550483713826, 5996.430339117776, 5276.577873770166, 6317.684115794284, 6322.795341098118, 6335.90496142549, 6364.6181229126405, 3283.9343213135126, 3463.653634636617, 3801.239752049546, 3914.64768170866, 3949.980250098731, 5650.40670729584, 5992.820215393545, 6015.939840601538, 6048.348838718936, 6055.303640081271, 5891.558133139946, 5908.7045647718305, 5908.754673224147, 5911.733971971185, 5917.526597872246, 5128.615353848401, 5594.321703488856, 5751.597388182787, 5882.1765252771775, 5886.440788125121, 4950.45544990058, 5600.887982240414, 5733.483986441241, 5738.856528586819, 5756.532084318472, 5185.533302002837, 5407.242985981887, 5728.942710573007, 5817.738201780845, 5833.183354330312, 4751.239801631722, 5408.810061786558, 5655.85860353478, 5666.206731057573, 5709.429057094258], "value": 4118.262148544256, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.8171566814287, 701.6702149989503, 739.7558805594155, 759.9848003039939, 796.9203079692028, 13871.514496992608, 14437.63905902353, 14459.638509037279, 14537.0, 14781.334839932202, 13263.602091937233, 13300.667483312922, 13376.197428154317, 13384.397702103428, 13472.989525785557, 632.1497585747186, 655.1155939481686, 791.6160662078887, 809.5142914251446, 819.0785366462729, 681.8431760695044, 703.7712743358403, 817.5748610722421, 831.2394159344223, 832.1096426823301, 856.1951765340591, 869.7043005378201, 875.9430637008605, 875.9737207883742, 878.3939082033409, 859.7936495241129, 870.3907264914585, 874.4665753890122, 874.6938571499987, 876.5967654878764, 626.7837596029362, 650.4113777031794, 652.1424327010014, 652.774792696519, 654.5647144648802, 11623.360715160663, 13520.459181632821, 13550.186988780704, 13567.45730170792, 13602.659933501644, 13019.934900325532, 13287.269200193985, 13325.400356983959, 13344.933275333562, 13442.395092220873, 11099.667009989713, 11181.552737890475, 11264.0, 11328.150388720796, 11717.472713727817, 3588.2464682416726, 3652.105234217607, 3676.246369494247, 3693.8891833244775, 3708.6662200401825, 73915.63042184808, 75331.0, 84106.57946710289, 84597.57701211455, 85477.57261213717, 9966.601335946625, 10388.065074143364, 10413.5834566618, 10415.531301091469, 10467.42429166388, 19604.313849015216, 19628.411147665593, 19796.70304945427, 19806.50483737918, 19879.403617891487, 7312.305330993547, 7393.223711510322, 7545.283198096172, 7662.310392064741, 7743.419243556756, 4211.957880421157, 4273.294906340481, 4280.315149576115, 4299.247631664446, 4406.20688276107, 2924.722151395614, 3294.8352582370785, 3372.6795954384293, 3403.591569011685, 3429.0398688367295, 632.7089538812198, 635.7171058878847, 639.0414378432351, 648.614074625597, 648.8021153548119, 632.9272133704591, 633.6144416272737, 649.7303618998158, 652.5040968863698, 654.4862283107738, 8670.86993695095, 8707.782305442288, 8908.910910890938, 9005.099490051074, 9067.637294508279, 621.8134559632092, 640.6284355074046, 664.6909187227963, 671.8589096289736, 674.6222115615271, 3733.533308336458, 4211.873643790691, 4405.537418571068, 4430.490493593214, 4466.1067786442845, 89582.55208723915, 92096.69758256097, 92472.0, 94628.52685736527, 114979.0, 59101.70449147727, 62554.687226563576, 77585.0, 80682.0, 83585.0, 56398.15402768962, 75095.0, 79806.0, 84066.15933840584, 84819.0, 9122.589483473392, 9424.905750942402, 9505.477198754135, 9545.379550329206, 9599.088086631758, 51015.0, 52009.77281698024, 52549.7372513135, 53887.191692123866, 53910.46089539054, 63418.0, 68034.65982670055, 71863.0, 74545.6272718633, 77635.22364776506, 10228.897711022795, 10420.32267902584, 11990.40047997614, 12029.819552706891, 12084.274943503418, 13512.189268643913, 13558.457661693421, 13582.388792504164, 13767.518136865161, 15020.324085415963, 12172.14794964344, 12633.17884337552, 13021.348932553145, 13157.276349800853, 13972.37124329428, 10798.838017429587, 11188.552457901593, 12517.436715348013, 13138.343082846011, 13495.730085398041, 12455.377231138587, 12842.229466232056, 12869.806952895528, 12940.417681204557, 13565.796513052115, 10567.260291779501, 10583.583553824805, 10706.839397408889, 11085.667429977113, 11135.0, 10786.352818830896, 10855.424214870163, 11050.06074483678, 11051.226414150933, 11110.055645270242], "value": 7064.996499770759, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1548.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 122.0, 118.0, 117.0, 119.0, 117.0, 117.0, 117.0, 117.0, 119.0], "value": 117.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 139.0, 138.0, 134.0, 131.0, 146.0, 145.0, 106.0, 171.0, 145.0], "value": 140.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 194.0, 196.0, 200.0, 193.0, 196.0, 195.0, 193.0, 196.0, 196.0], "value": 195.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 58.0, 59.0, 84.0, 59.0, 58.0, 58.0, 57.0, 76.0, 74.0], "value": 67.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 69.0, 90.0, 72.0, 70.0, 77.0, 73.0, 57.0, 60.0, 86.0], "value": 73.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 102.0, 102.0, 106.0, 135.0, 132.0, 101.0, 96.0, 135.0, 107.0], "value": 110.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 94.0, 95.0, 95.0, 103.0, 95.0, 102.0, 141.0, 95.0, 123.0], "value": 109.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 86.0, 93.0, 89.0, 89.0, 93.0, 91.0, 88.0, 100.0], "value": 90.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 51.0, 87.0, 90.0, 90.0, 60.0, 56.0, 78.0, 52.0, 56.0], "value": 66.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 80.0, 78.0, 75.0, 71.0, 73.0, 71.0, 71.0, 99.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 105.0, 79.0, 84.0, 86.0, 85.0, 84.0, 84.0, 85.0, 85.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 149.0, 146.0, 150.0, 163.0, 151.0, 148.0, 153.0, 212.0, 162.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 239.0, 169.0, 170.0, 170.0, 172.0, 182.0, 169.0, 168.0, 227.0], "value": 183.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 68.0, 69.0, 69.0, 67.0, 73.0, 66.0, 91.0, 91.0], "value": 73.5, "unit": "score"}]}]} 04:17:21 INFO - Return code: 0 04:17:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:17:21 ERROR - # TBPL SUCCESS # 04:17:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:17:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:17:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:17:21 INFO - 2016-12-01 04:17:21.847 screenresolution[1663:11002] starting screenresolution argv=screenresolution get 04:17:21 INFO - 2016-12-01 04:17:21.850 screenresolution[1663:11002] Display 0: 1600x1200x32@60 04:17:21 INFO - 2016-12-01 04:17:21.856 screenresolution[1664:11005] starting screenresolution argv=screenresolution list 04:17:21 INFO - Available Modes on Display 0 04:17:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:17:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:17:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:17:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:17:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:17:22 INFO - Intel Iris: 04:17:22 INFO - Chipset Model: Intel Iris 04:17:22 INFO - Type: GPU 04:17:22 INFO - Bus: Built-In 04:17:22 INFO - VRAM (Dynamic, Max): 1536 MB 04:17:22 INFO - Vendor: Intel (0x8086) 04:17:22 INFO - Device ID: 0x0a2e 04:17:22 INFO - Revision ID: 0x0009 04:17:22 INFO - Displays: 04:17:22 INFO - Display: 04:17:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:17:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:17:22 INFO - Display Serial Number: 1600x1200 60 04:17:22 INFO - Main Display: Yes 04:17:22 INFO - Mirror: Off 04:17:22 INFO - Online: Yes 04:17:22 INFO - Rotation: Supported 04:17:22 INFO - Return code: 0 04:17:22 INFO - Running post-action listener: _resource_record_post_action 04:17:22 INFO - [mozharness: 2016-12-01 12:17:22.108204Z] Finished run-tests step (success) 04:17:22 INFO - Running post-run listener: _resource_record_post_run 04:17:22 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38815744; Write bytes: 661446656; Read time: 244; Write time: 1947 04:17:22 INFO - TinderboxPrint: CPU usage
54.4% 04:17:22 INFO - TinderboxPrint: I/O read bytes / time
38,815,744 / 244 04:17:22 INFO - TinderboxPrint: I/O write bytes / time
661,446,656 / 1,947 04:17:22 INFO - TinderboxPrint: CPU idle
2,207.5 (45.6%) 04:17:22 INFO - TinderboxPrint: CPU system
979.4 (20.2%) 04:17:22 INFO - TinderboxPrint: CPU user
1,658.8 (34.2%) 04:17:22 INFO - TinderboxPrint: Swap in / out
387,801,088 / 0 04:17:22 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345778688; Write bytes: 345716736; Read time: 26790; Write time: 722 04:17:22 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 36706304; Write bytes: 308541440; Read time: 226; Write time: 1196 04:17:22 INFO - Running post-run listener: _upload_blobber_files 04:17:22 INFO - Blob upload gear active. 04:17:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:17:22 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 04:17:22 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'] 04:17:22 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 04:17:23 INFO - (blobuploader) - INFO - Open directory for files ... 04:17:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:17:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:17:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:17:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:17:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:17:24 INFO - (blobuploader) - INFO - Done attempting. 04:17:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:17:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:17:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:17:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:17:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:17:25 INFO - (blobuploader) - INFO - Done attempting. 04:17:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:17:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:17:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:17:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:17:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:17:28 INFO - (blobuploader) - INFO - Done attempting. 04:17:28 INFO - (blobuploader) - INFO - Iteration through files over. 04:17:28 INFO - Return code: 0 04:17:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:17:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:17:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c66b0e1b49aaf84f5f7013b8b0ef3cd2dbb64bf6285a065ab40f740ebf7b4dfee7b4e1f1938977b01441cd825be5be727308aec26e97d485560c9c2c1094b53e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5c65bc9839af4f2c9c5131df64d9e0c85a166c6efea06802ca0209fb548913d438480bf4352e178ede9672502f0e444d016e27909121a7cf509307ab4998bb64", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3a24882afb22aec5732fb80b236c761d46cfbd5764c21ce2c395a670c2727a56d4df6615ca1e09be220b73167cdc276e6e1556ffbd6536faf422819a9007ce1"} 04:17:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:17:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:17:28 INFO - Contents: 04:17:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c66b0e1b49aaf84f5f7013b8b0ef3cd2dbb64bf6285a065ab40f740ebf7b4dfee7b4e1f1938977b01441cd825be5be727308aec26e97d485560c9c2c1094b53e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5c65bc9839af4f2c9c5131df64d9e0c85a166c6efea06802ca0209fb548913d438480bf4352e178ede9672502f0e444d016e27909121a7cf509307ab4998bb64", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3a24882afb22aec5732fb80b236c761d46cfbd5764c21ce2c395a670c2727a56d4df6615ca1e09be220b73167cdc276e6e1556ffbd6536faf422819a9007ce1"} 04:17:28 INFO - Running post-run listener: copy_logs_to_upload_dir 04:17:28 INFO - Copying logs to upload dir... 04:17:28 INFO - mkdir: /builds/slave/test/build/upload/logs 04:17:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.468044 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-01 04:17:28.083857) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-01 04:17:28.087657) ========= 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.WR2aCq0kmG/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.NGVDkJPdYD/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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/c66b0e1b49aaf84f5f7013b8b0ef3cd2dbb64bf6285a065ab40f740ebf7b4dfee7b4e1f1938977b01441cd825be5be727308aec26e97d485560c9c2c1094b53e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5c65bc9839af4f2c9c5131df64d9e0c85a166c6efea06802ca0209fb548913d438480bf4352e178ede9672502f0e444d016e27909121a7cf509307ab4998bb64", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3a24882afb22aec5732fb80b236c761d46cfbd5764c21ce2c395a670c2727a56d4df6615ca1e09be220b73167cdc276e6e1556ffbd6536faf422819a9007ce1"} build_url:https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010629 build_url: 'https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/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/c66b0e1b49aaf84f5f7013b8b0ef3cd2dbb64bf6285a065ab40f740ebf7b4dfee7b4e1f1938977b01441cd825be5be727308aec26e97d485560c9c2c1094b53e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5c65bc9839af4f2c9c5131df64d9e0c85a166c6efea06802ca0209fb548913d438480bf4352e178ede9672502f0e444d016e27909121a7cf509307ab4998bb64", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3a24882afb22aec5732fb80b236c761d46cfbd5764c21ce2c395a670c2727a56d4df6615ca1e09be220b73167cdc276e6e1556ffbd6536faf422819a9007ce1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-01 04:17:28.121399) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 04:17:28.121733) ========= 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.WR2aCq0kmG/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.NGVDkJPdYD/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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.004998 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 04:17:28.170113) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-01 04:17:28.170447) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-01 04:17:28.171010) ========= ========= Total master_lag: 0.22 =========