builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0291 starttime: 1493041904.08 results: success (0) buildid: 20170424060619 builduid: 91dc8e53940f4998bffa3d7440f94e5a revision: d5fe7a284cf1c77bc7a258f85f80ba80a051ae0d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-24 06:51:44.082061) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-24 06:51:44.082571) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-24 06:51:44.082861) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-24 06:51:44.219267) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 06:51:44.219659) ========= 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.7rgF4xVhV6/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.KZ4IsaeT2M/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-24 06:51:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 164M=0s 2017-04-24 06:51:44 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.550172 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 06:51:44.795868) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 06:51:44.796179) ========= 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.7rgF4xVhV6/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.KZ4IsaeT2M/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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.024542 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 06:51:44.844919) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-24 06:51:44.845310) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d5fe7a284cf1c77bc7a258f85f80ba80a051ae0d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d5fe7a284cf1c77bc7a258f85f80ba80a051ae0d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7rgF4xVhV6/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.KZ4IsaeT2M/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-24 06:51:44,944 truncating revision to first 12 chars 2017-04-24 06:51:44,944 Setting DEBUG logging. 2017-04-24 06:51:44,944 attempt 1/10 2017-04-24 06:51:44,944 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d5fe7a284cf1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-24 06:51:46,385 unpacking tar archive at: mozilla-beta-d5fe7a284cf1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.705087 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-24 06:51:46.575092) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-24 06:51:46.575600) ========= script_repo_revision: d5fe7a284cf1c77bc7a258f85f80ba80a051ae0d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-24 06:51:46.576212) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-24 06:51:46.576548) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-24 06:51:46.597534) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-04-24 06:51:46.597969) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7rgF4xVhV6/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.KZ4IsaeT2M/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 06:51:46 INFO - MultiFileLogger online at 20170424 06:51:46 in /builds/slave/test 06:51:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 06:51:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:51:46 INFO - {'append_to_log': False, 06:51:46 INFO - 'base_work_dir': '/builds/slave/test', 06:51:46 INFO - 'blob_upload_branch': 'Mozilla-Beta', 06:51:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:51:46 INFO - 'branch': 'Mozilla-Beta', 06:51:46 INFO - 'buildbot_json_path': 'buildprops.json', 06:51:46 INFO - 'config_files': ('talos/mac_config.py',), 06:51:46 INFO - 'default_actions': ('clobber', 06:51:46 INFO - 'read-buildbot-config', 06:51:46 INFO - 'download-and-extract', 06:51:46 INFO - 'populate-webroot', 06:51:46 INFO - 'create-virtualenv', 06:51:46 INFO - 'install', 06:51:46 INFO - 'run-tests'), 06:51:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:51:46 INFO - 'download_minidump_stackwalk': True, 06:51:46 INFO - 'download_symbols': 'ondemand', 06:51:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:51:46 INFO - 'tooltool.py': '/tools/tooltool.py', 06:51:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:51:46 INFO - '/tools/misc-python/virtualenv.py')}, 06:51:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:51:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:51:46 INFO - 'gecko_profile': False, 06:51:46 INFO - 'gecko_profile_interval': 0, 06:51:46 INFO - 'installer_path': 'installer.exe', 06:51:46 INFO - 'log_level': 'info', 06:51:46 INFO - 'log_name': 'talos', 06:51:46 INFO - 'log_to_console': True, 06:51:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:51:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:51:46 INFO - 'opt_config_files': (), 06:51:46 INFO - 'pip_index': False, 06:51:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:51:46 INFO - 'cmd': ('bash', 06:51:46 INFO - '-c', 06:51:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:51:46 INFO - 'enabled': True, 06:51:46 INFO - 'halt_on_failure': False, 06:51:46 INFO - 'name': 'check_screen_resolution'},), 06:51:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:51:46 INFO - 'cmd': ('bash', 06:51:46 INFO - '-c', 06:51:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:51:46 INFO - 'enabled': True, 06:51:46 INFO - 'halt_on_failure': False, 06:51:46 INFO - 'name': 'check_screen_resolution'},), 06:51:46 INFO - 'run_cmd_checks_enabled': True, 06:51:46 INFO - 'suite': 'dromaeojs-e10s', 06:51:46 INFO - 'system_bits': '32', 06:51:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:51:46 INFO - 'title': 't-yosemite-r7-0291', 06:51:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:51:46 INFO - 'use_talos_json': True, 06:51:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:51:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:51:46 INFO - 'work_dir': 'build'} 06:51:46 INFO - [mozharness: 2017-04-24 13:51:46.814121Z] Running clobber step. 06:51:46 INFO - Running pre-action listener: _resource_record_pre_action 06:51:46 INFO - Running main action method: clobber 06:51:46 INFO - rmtree: /builds/slave/test/build 06:51:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:51:49 INFO - Running post-action listener: _resource_record_post_action 06:51:49 INFO - [mozharness: 2017-04-24 13:51:49.358855Z] Finished clobber step (success) 06:51:49 INFO - [mozharness: 2017-04-24 13:51:49.358991Z] Running read-buildbot-config step. 06:51:49 INFO - Running pre-action listener: _resource_record_pre_action 06:51:49 INFO - Running main action method: read_buildbot_config 06:51:49 INFO - Using buildbot properties: 06:51:49 INFO - { 06:51:49 INFO - "project": "", 06:51:49 INFO - "product": "firefox", 06:51:49 INFO - "script_repo_revision": "production", 06:51:49 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 06:51:49 INFO - "repository": "", 06:51:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 06:51:49 INFO - "buildid": "20170424060619", 06:51:49 INFO - "pgo_build": "False", 06:51:49 INFO - "basedir": "/builds/slave/test", 06:51:49 INFO - "buildnumber": 16, 06:51:49 INFO - "slavename": "t-yosemite-r7-0291", 06:51:49 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 06:51:49 INFO - "platform": "yosemite_r7", 06:51:49 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 06:51:49 INFO - "branch": "mozilla-beta", 06:51:49 INFO - "repo_path": "releases/mozilla-beta", 06:51:49 INFO - "revision": "d5fe7a284cf1c77bc7a258f85f80ba80a051ae0d", 06:51:49 INFO - "stage_platform": "macosx64", 06:51:49 INFO - "builduid": "91dc8e53940f4998bffa3d7440f94e5a", 06:51:49 INFO - "slavebuilddir": "test" 06:51:49 INFO - } 06:51:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 06:51:49 INFO - Running post-action listener: _resource_record_post_action 06:51:49 INFO - [mozharness: 2017-04-24 13:51:49.363210Z] Finished read-buildbot-config step (success) 06:51:49 INFO - [mozharness: 2017-04-24 13:51:49.363335Z] Running download-and-extract step. 06:51:49 INFO - Running pre-action listener: _resource_record_pre_action 06:51:49 INFO - Running main action method: download_and_extract 06:51:49 INFO - mkdir: /builds/slave/test/build/tests 06:51:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:49 INFO - trying https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 06:51:49 INFO - Downloading https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 06:51:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 06:51:50 INFO - Downloaded 1165 bytes. 06:51:50 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 06:51:50 INFO - Using the following test package requirements: 06:51:50 INFO - {u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 06:51:50 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:51:50 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 06:51:50 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:51:50 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 06:51:50 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 06:51:50 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:51:50 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 06:51:50 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 06:51:50 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:51:50 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 06:51:50 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:51:50 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 06:51:50 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:51:50 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 06:51:50 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:51:50 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 06:51:50 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 06:51:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 06:51:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 06:51:50 INFO - Fetch https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 06:51:52 INFO - Expected file size: 51221063 06:51:52 INFO - Obtained file size: 51221063 06:52:07 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 06:52:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 06:52:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 06:52:07 INFO - Fetch https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 06:52:09 INFO - Expected file size: 51221063 06:52:09 INFO - Obtained file size: 51221063 06:52:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 06:52:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 06:52:24 INFO - Fetch https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 06:52:25 INFO - Expected file size: 14404449 06:52:25 INFO - Obtained file size: 14404449 06:52:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:52:26 INFO - trying https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:52:26 INFO - Downloading https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 06:52:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 06:52:28 INFO - Downloaded 59540929 bytes. 06:52:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:52:28 INFO - mkdir: /builds/slave/test/properties 06:52:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:52:28 INFO - Writing to file /builds/slave/test/properties/build_url 06:52:28 INFO - Contents: 06:52:28 INFO - build_url:https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:52:29 INFO - Running post-action listener: _resource_record_post_action 06:52:29 INFO - Running post-action listener: set_extra_try_arguments 06:52:29 INFO - [mozharness: 2017-04-24 13:52:29.132329Z] Finished download-and-extract step (success) 06:52:29 INFO - [mozharness: 2017-04-24 13:52:29.132471Z] Running populate-webroot step. 06:52:29 INFO - Running pre-action listener: _resource_record_pre_action 06:52:29 INFO - Running main action method: populate_webroot 06:52:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:52:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:52:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:52:29 INFO - '--tppagecycles', 06:52:29 INFO - '7'], 06:52:29 INFO - 'tests': ['tsvgm']}}, 06:52:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:52:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:52:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:52:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:52:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:52:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:52:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:52:29 INFO - 'talos_options': ['--disable-e10s'], 06:52:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:52:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:52:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:52:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:52:29 INFO - 'talos_options': ['--disable-e10s'], 06:52:29 INFO - 'tests': ['damp', 'tps']}, 06:52:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:52:29 INFO - 'tests': ['damp', 'tps']}, 06:52:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:52:29 INFO - 'tests': ['dromaeo_dom']}, 06:52:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:52:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:52:29 INFO - 'tests': ['basic_compositor_video']}, 06:52:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:52:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:52:29 INFO - 'tests': ['a11yr', 06:52:29 INFO - 'ts_paint', 06:52:29 INFO - 'tpaint', 06:52:29 INFO - 'sessionrestore', 06:52:29 INFO - 'sessionrestore_no_auto_restore', 06:52:29 INFO - 'tabpaint']}, 06:52:29 INFO - 'other-e10s': {'tests': ['a11yr', 06:52:29 INFO - 'ts_paint', 06:52:29 INFO - 'tpaint', 06:52:29 INFO - 'sessionrestore', 06:52:29 INFO - 'sessionrestore_no_auto_restore', 06:52:29 INFO - 'tabpaint']}, 06:52:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:52:29 INFO - 'ts_paint', 06:52:29 INFO - 'tpaint', 06:52:29 INFO - 'sessionrestore', 06:52:29 INFO - 'sessionrestore_no_auto_restore', 06:52:29 INFO - 'tabpaint']}, 06:52:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:52:29 INFO - 'ts_paint', 06:52:29 INFO - 'tpaint', 06:52:29 INFO - 'sessionrestore', 06:52:29 INFO - 'sessionrestore_no_auto_restore', 06:52:29 INFO - 'tabpaint']}, 06:52:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:52:29 INFO - 'tests': ['a11yr', 06:52:29 INFO - 'ts_paint', 06:52:29 INFO - 'tpaint', 06:52:29 INFO - 'sessionrestore', 06:52:29 INFO - 'sessionrestore_no_auto_restore', 06:52:29 INFO - 'tabpaint']}, 06:52:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:52:29 INFO - 'tests': ['a11yr', 06:52:29 INFO - 'ts_paint', 06:52:29 INFO - 'tpaint', 06:52:29 INFO - 'sessionrestore', 06:52:29 INFO - 'sessionrestore_no_auto_restore', 06:52:29 INFO - 'tabpaint']}, 06:52:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:52:29 INFO - 'tests': ['tsvgx', 06:52:29 INFO - 'tsvgr_opacity', 06:52:29 INFO - 'tart', 06:52:29 INFO - 'tscrollx', 06:52:29 INFO - 'cart', 06:52:29 INFO - 'tsvg_static']}, 06:52:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:52:29 INFO - 'tsvgr_opacity', 06:52:29 INFO - 'tart', 06:52:29 INFO - 'tscrollx', 06:52:29 INFO - 'cart', 06:52:29 INFO - 'tsvg_static']}, 06:52:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:52:29 INFO - 'talos_options': ['--disable-e10s'], 06:52:29 INFO - 'tests': ['tp5o']}, 06:52:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:52:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:52:29 INFO - 'talos_options': ['--disable-e10s', 06:52:29 INFO - '--xperf_path', 06:52:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:52:29 INFO - 'tests': ['tp5n']}, 06:52:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:52:29 INFO - 'talos_options': ['--xperf_path', 06:52:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:52:29 INFO - 'tests': ['tp5n']}}, 06:52:29 INFO - 'talos.zip': {'path': '', 06:52:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:52:29 INFO - Running post-action listener: _resource_record_post_action 06:52:29 INFO - [mozharness: 2017-04-24 13:52:29.151148Z] Finished populate-webroot step (success) 06:52:29 INFO - [mozharness: 2017-04-24 13:52:29.151284Z] Running create-virtualenv step. 06:52:29 INFO - Running pre-action listener: _resource_record_pre_action 06:52:29 INFO - Running main action method: create_virtualenv 06:52:29 INFO - Creating virtualenv /builds/slave/test/build/venv 06:52:29 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 06:52:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:52:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:52:29 INFO - Using real prefix '/tools/python27' 06:52:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:52:30 INFO - Installing distribute.............................................................................................................................................................................................done. 06:52:32 INFO - Installing pip.................done. 06:52:32 INFO - Return code: 0 06:52:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:52:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:52:33 INFO - Reading from file tmpfile_stdout 06:52:33 INFO - Output received: 06:52:33 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) 06:52:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:52:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:52:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:52:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eeecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eeef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe35b594f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eccad30>, '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 0x10ebf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecbfbe8>, '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.7rgF4xVhV6/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.KZ4IsaeT2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 06:52:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:52:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:52:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7rgF4xVhV6/Render', 06:52:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:52:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:52:33 INFO - 'HOME': '/Users/cltbld', 06:52:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:52:33 INFO - 'LOGNAME': 'cltbld', 06:52:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:52:33 INFO - 'MOZ_NO_REMOTE': '1', 06:52:33 INFO - 'NO_EM_RESTART': '1', 06:52:33 INFO - 'PAGER': '/bin/cat', 06:52:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:52:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:52:33 INFO - 'PWD': '/builds/slave/test', 06:52:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:52:33 INFO - 'SHELL': '/bin/bash', 06:52:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZ4IsaeT2M/Listeners', 06:52:33 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 06:52:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:52:33 INFO - 'USER': 'cltbld', 06:52:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:52:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:52:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:52:33 INFO - 'XPC_FLAGS': '0x0', 06:52:33 INFO - 'XPC_SERVICE_NAME': '0', 06:52:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:52:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:52:33 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 06:52:33 INFO - Cleaning up... 06:52:33 INFO - Return code: 0 06:52:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:52:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:52:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:52:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10eeecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eeef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe35b594f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eccad30>, '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 0x10ebf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecbfbe8>, '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.7rgF4xVhV6/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.KZ4IsaeT2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 06:52:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:52:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:52:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7rgF4xVhV6/Render', 06:52:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:52:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:52:33 INFO - 'HOME': '/Users/cltbld', 06:52:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:52:33 INFO - 'LOGNAME': 'cltbld', 06:52:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:52:33 INFO - 'MOZ_NO_REMOTE': '1', 06:52:33 INFO - 'NO_EM_RESTART': '1', 06:52:33 INFO - 'PAGER': '/bin/cat', 06:52:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:52:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:52:33 INFO - 'PWD': '/builds/slave/test', 06:52:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:52:33 INFO - 'SHELL': '/bin/bash', 06:52:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZ4IsaeT2M/Listeners', 06:52:33 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 06:52:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:52:33 INFO - 'USER': 'cltbld', 06:52:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:52:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:52:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:52:33 INFO - 'XPC_FLAGS': '0x0', 06:52:33 INFO - 'XPC_SERVICE_NAME': '0', 06:52:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:52:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:52:33 INFO - Downloading/unpacking psutil>=3.1.1 06:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:52:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:52:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:52:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:52:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:52:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:52:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:52:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:52:37 INFO - Installing collected packages: psutil 06:52:37 INFO - Running setup.py install for psutil 06:52:37 INFO - building 'psutil._psutil_osx' extension 06:52:37 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 06:52:38 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 06:52:38 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 06:52:38 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 06:52:38 INFO - building 'psutil._psutil_posix' extension 06:52:38 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 06:52:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:52:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:52:38 INFO - ^ 06:52:38 INFO - 1 warning generated. 06:52:38 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 06:52:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:52:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:52:38 INFO - Successfully installed psutil 06:52:38 INFO - Cleaning up... 06:52:38 INFO - Return code: 0 06:52:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:52:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:52:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:52:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:38 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 0x10eeecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eeef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe35b594f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eccad30>, '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 0x10ebf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecbfbe8>, '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.7rgF4xVhV6/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.KZ4IsaeT2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 06:52:38 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 06:52:38 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 06:52:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7rgF4xVhV6/Render', 06:52:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:52:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:52:38 INFO - 'HOME': '/Users/cltbld', 06:52:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:52:38 INFO - 'LOGNAME': 'cltbld', 06:52:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:52:38 INFO - 'MOZ_NO_REMOTE': '1', 06:52:38 INFO - 'NO_EM_RESTART': '1', 06:52:38 INFO - 'PAGER': '/bin/cat', 06:52:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:52:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:52:38 INFO - 'PWD': '/builds/slave/test', 06:52:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:52:38 INFO - 'SHELL': '/bin/bash', 06:52:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZ4IsaeT2M/Listeners', 06:52:38 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 06:52:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:52:38 INFO - 'USER': 'cltbld', 06:52:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:52:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:52:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:52:38 INFO - 'XPC_FLAGS': '0x0', 06:52:38 INFO - 'XPC_SERVICE_NAME': '0', 06:52:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:52:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:52:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:52:38 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 06:52:38 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 06:52:38 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 06:52:38 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 06:52:38 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 06:52:38 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 06:52:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:52:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:52:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:52:41 INFO - Installing collected packages: mozsystemmonitor 06:52:41 INFO - Running setup.py install for mozsystemmonitor 06:52:42 INFO - Successfully installed mozsystemmonitor 06:52:42 INFO - Cleaning up... 06:52:42 INFO - Return code: 0 06:52:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:52: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')]} 06:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:52:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52: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')]} 06:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:52:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10eeecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eeef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe35b594f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eccad30>, '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 0x10ebf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecbfbe8>, '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.7rgF4xVhV6/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.KZ4IsaeT2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 06:52:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:52:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:52:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7rgF4xVhV6/Render', 06:52:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:52:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:52:42 INFO - 'HOME': '/Users/cltbld', 06:52:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:52:42 INFO - 'LOGNAME': 'cltbld', 06:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:52:42 INFO - 'MOZ_NO_REMOTE': '1', 06:52:42 INFO - 'NO_EM_RESTART': '1', 06:52:42 INFO - 'PAGER': '/bin/cat', 06:52:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:52:42 INFO - 'PWD': '/builds/slave/test', 06:52:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:52:42 INFO - 'SHELL': '/bin/bash', 06:52:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZ4IsaeT2M/Listeners', 06:52:42 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 06:52:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:52:42 INFO - 'USER': 'cltbld', 06:52:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:52:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:52:42 INFO - 'XPC_FLAGS': '0x0', 06:52:42 INFO - 'XPC_SERVICE_NAME': '0', 06:52:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:52:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:52:42 INFO - Downloading/unpacking jsonschema==2.5.1 06:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:52:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:52:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:52:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:52:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:52:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:52:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:52:44 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 06:52:44 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 06:52:44 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 06:52:44 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 06:52:44 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 06:52:44 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 06:52:45 INFO - Downloading functools32-3.2.3-2.tar.gz 06:52:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:52:45 INFO - warning: no files found matching '*.txt' 06:52:45 INFO - no previously-included directories found matching 'build' 06:52:45 INFO - no previously-included directories found matching 'dist' 06:52:45 INFO - no previously-included directories found matching '.git*' 06:52:45 INFO - Installing collected packages: jsonschema, functools32 06:52:45 INFO - Running setup.py install for functools32 06:52:45 INFO - warning: no files found matching '*.txt' 06:52:45 INFO - no previously-included directories found matching 'build' 06:52:45 INFO - no previously-included directories found matching 'dist' 06:52:45 INFO - no previously-included directories found matching '.git*' 06:52:45 INFO - Successfully installed jsonschema functools32 06:52:45 INFO - Cleaning up... 06:52:45 INFO - Return code: 0 06:52:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:52:45 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')]} 06:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:52:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:45 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')]} 06:52:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:52:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:45 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 0x10eeecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eeef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe35b594f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eccad30>, '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 0x10ebf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecbfbe8>, '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.7rgF4xVhV6/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.KZ4IsaeT2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 06:52:45 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 06:52:45 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 06:52:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7rgF4xVhV6/Render', 06:52:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:52:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:52:45 INFO - 'HOME': '/Users/cltbld', 06:52:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:52:45 INFO - 'LOGNAME': 'cltbld', 06:52:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:52:45 INFO - 'MOZ_NO_REMOTE': '1', 06:52:45 INFO - 'NO_EM_RESTART': '1', 06:52:45 INFO - 'PAGER': '/bin/cat', 06:52:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:52:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:52:45 INFO - 'PWD': '/builds/slave/test', 06:52:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:52:45 INFO - 'SHELL': '/bin/bash', 06:52:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZ4IsaeT2M/Listeners', 06:52:45 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 06:52:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:52:45 INFO - 'USER': 'cltbld', 06:52:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:52:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:52:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:52:45 INFO - 'XPC_FLAGS': '0x0', 06:52:45 INFO - 'XPC_SERVICE_NAME': '0', 06:52:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:52:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:52:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:52:45 INFO - Cleaning up... 06:52:46 INFO - Return code: 0 06:52:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:52: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')]} 06:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:52:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52: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')]} 06:52:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:52:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52: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', '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 0x10eeecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eeef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe35b594f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eccad30>, '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 0x10ebf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecbfbe8>, '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.7rgF4xVhV6/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.KZ4IsaeT2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 06:52: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:52: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 blobuploader==1.2.4 06:52:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7rgF4xVhV6/Render', 06:52:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:52:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:52:46 INFO - 'HOME': '/Users/cltbld', 06:52:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:52:46 INFO - 'LOGNAME': 'cltbld', 06:52:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:52:46 INFO - 'MOZ_NO_REMOTE': '1', 06:52:46 INFO - 'NO_EM_RESTART': '1', 06:52:46 INFO - 'PAGER': '/bin/cat', 06:52:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:52:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:52:46 INFO - 'PWD': '/builds/slave/test', 06:52:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:52:46 INFO - 'SHELL': '/bin/bash', 06:52:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZ4IsaeT2M/Listeners', 06:52:46 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 06:52:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:52:46 INFO - 'USER': 'cltbld', 06:52:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:52:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:52:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:52:46 INFO - 'XPC_FLAGS': '0x0', 06:52:46 INFO - 'XPC_SERVICE_NAME': '0', 06:52:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:52:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:52:46 INFO - Downloading/unpacking blobuploader==1.2.4 06:52: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 06:52: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 06:52: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 06:52: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 06:52: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 06:52: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 06:52:48 INFO - Downloading blobuploader-1.2.4.tar.gz 06:52:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:52:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:52:49 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 06:52:49 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 06:52:49 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 06:52:49 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 06:52:49 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 06:52:49 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 06:52:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:52:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:52:49 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 06:52:49 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 06:52:49 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 06:52:49 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 06:52:49 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 06:52:49 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 06:52:50 INFO - Downloading docopt-0.6.1.tar.gz 06:52:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:52:50 INFO - Installing collected packages: blobuploader, requests, docopt 06:52:50 INFO - Running setup.py install for blobuploader 06:52:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:52:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:52:50 INFO - Running setup.py install for requests 06:52:50 INFO - Running setup.py install for docopt 06:52:51 INFO - Successfully installed blobuploader requests docopt 06:52:51 INFO - Cleaning up... 06:52:51 INFO - Return code: 0 06:52:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:52:51 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')]} 06:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:52:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:51 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')]} 06:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:52:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:51 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 0x10eeecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eeef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe35b594f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eccad30>, '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 0x10ebf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecbfbe8>, '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.7rgF4xVhV6/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.KZ4IsaeT2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 06:52:51 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 06:52:51 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 06:52:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7rgF4xVhV6/Render', 06:52:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:52:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:52:51 INFO - 'HOME': '/Users/cltbld', 06:52:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:52:51 INFO - 'LOGNAME': 'cltbld', 06:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:52:51 INFO - 'MOZ_NO_REMOTE': '1', 06:52:51 INFO - 'NO_EM_RESTART': '1', 06:52:51 INFO - 'PAGER': '/bin/cat', 06:52:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:52:51 INFO - 'PWD': '/builds/slave/test', 06:52:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:52:51 INFO - 'SHELL': '/bin/bash', 06:52:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZ4IsaeT2M/Listeners', 06:52:51 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 06:52:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:52:51 INFO - 'USER': 'cltbld', 06:52:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:52:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:52:51 INFO - 'XPC_FLAGS': '0x0', 06:52:51 INFO - 'XPC_SERVICE_NAME': '0', 06:52:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:52:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:52:51 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-wKAqro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:52:51 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-BVg9yt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:52:51 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-n9KBt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:52:51 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-CNseCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:52:51 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-lL61Kz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:52:51 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-HP8vRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:52:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-dYZSFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:52:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-JQXImV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:52:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-0eWsqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:52:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-UpS03J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:52:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-BmJl4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:52:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-WScz5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:52:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-gQwsmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:52:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-KOFNmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:52:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-221bNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:52:53 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-sa9V5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:52:53 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-4MnWA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:52:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:52:53 INFO - Running setup.py install for manifestparser 06:52:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:52:53 INFO - Running setup.py install for mozcrash 06:52:53 INFO - Running setup.py install for mozdebug 06:52:53 INFO - Running setup.py install for mozdevice 06:52:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:52:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:52:53 INFO - Running setup.py install for mozfile 06:52:53 INFO - Running setup.py install for mozhttpd 06:52:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:52:54 INFO - Running setup.py install for mozinfo 06:52:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:52:54 INFO - Running setup.py install for mozInstall 06:52:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:52:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:52:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:52:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:52:54 INFO - Running setup.py install for mozleak 06:52:54 INFO - Running setup.py install for mozlog 06:52:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:52:54 INFO - Running setup.py install for moznetwork 06:52:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:52:54 INFO - Running setup.py install for mozprocess 06:52:55 INFO - Running setup.py install for mozprofile 06:52:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:52:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:52:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:52:55 INFO - Running setup.py install for mozrunner 06:52:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:52:55 INFO - Running setup.py install for mozscreenshot 06:52:55 INFO - Running setup.py install for moztest 06:52:55 INFO - Running setup.py install for mozversion 06:52:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:52:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:52:55 INFO - Cleaning up... 06:52:55 INFO - Return code: 0 06:52:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:52:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:52:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:52:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:52:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:52:55 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 0x10eeecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eeef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe35b594f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eccad30>, '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 0x10ebf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecbfbe8>, '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.7rgF4xVhV6/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.KZ4IsaeT2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 06:52:55 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 06:52:55 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 06:52:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7rgF4xVhV6/Render', 06:52:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:52:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:52:55 INFO - 'HOME': '/Users/cltbld', 06:52:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:52:55 INFO - 'LOGNAME': 'cltbld', 06:52:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:52:55 INFO - 'MOZ_NO_REMOTE': '1', 06:52:55 INFO - 'NO_EM_RESTART': '1', 06:52:55 INFO - 'PAGER': '/bin/cat', 06:52:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:52:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:52:55 INFO - 'PWD': '/builds/slave/test', 06:52:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:52:55 INFO - 'SHELL': '/bin/bash', 06:52:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZ4IsaeT2M/Listeners', 06:52:55 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 06:52:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:52:55 INFO - 'USER': 'cltbld', 06:52:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:52:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:52:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:52:55 INFO - 'XPC_FLAGS': '0x0', 06:52:55 INFO - 'XPC_SERVICE_NAME': '0', 06:52:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:52:56 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-d7zs95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:52:56 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)) 06:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:52:56 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-jIu1CR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:52:56 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)) 06:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:52:56 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-I4AhH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:52:56 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)) 06:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:52:56 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-_NFqYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 06:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:52:56 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-zWuRLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:52:56 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)) 06:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:52:56 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-CNXkxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:52:56 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)) 06:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:52:56 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-e2D9rb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:52:56 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)) 06:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:52:56 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-tSjjvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:52:56 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)) 06:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:52:56 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-sCBXO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:52:57 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)) 06:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:52:57 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-8y6L9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:52:57 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)) 06:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:52:57 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-x1HR4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:52:57 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)) 06:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:52:57 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-VEYYsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:52:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 06:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:52:57 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-94l0PO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:52:57 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)) 06:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:52:57 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-gg0AJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:52:57 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)) 06:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:52:57 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-TEG9ka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:52:57 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)) 06:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:52:57 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-5pv3YY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:52:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:52:57 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-EqJIFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:52:57 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)) 06:52:57 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)) 06:52:57 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)) 06:52:57 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)) 06:52:57 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:52:57 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:52:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:52: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 06:52: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 06:52: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 06:52: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 06:52: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 06:52: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 06:53:00 INFO - Downloading blessings-1.6.tar.gz 06:53:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:53:00 INFO - Installing collected packages: blessings 06:53:00 INFO - Running setup.py install for blessings 06:53:00 INFO - Successfully installed blessings 06:53:00 INFO - Cleaning up... 06:53:00 INFO - Return code: 0 06:53:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:53:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:53:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:53:01 INFO - Reading from file tmpfile_stdout 06:53:01 INFO - Current package versions: 06:53:01 INFO - blessings == 1.6 06:53:01 INFO - blobuploader == 1.2.4 06:53:01 INFO - docopt == 0.6.1 06:53:01 INFO - functools32 == 3.2.3-2 06:53:01 INFO - jsonschema == 2.5.1 06:53:01 INFO - manifestparser == 1.1 06:53:01 INFO - mozInstall == 1.12 06:53:01 INFO - mozcrash == 1.0 06:53:01 INFO - mozdebug == 0.1 06:53:01 INFO - mozdevice == 0.49 06:53:01 INFO - mozfile == 1.2 06:53:01 INFO - mozhttpd == 0.7 06:53:01 INFO - mozinfo == 0.9 06:53:01 INFO - mozleak == 0.1 06:53:01 INFO - mozlog == 3.4 06:53:01 INFO - moznetwork == 0.27 06:53:01 INFO - mozprocess == 0.25 06:53:01 INFO - mozprofile == 0.28 06:53:01 INFO - mozrunner == 6.13 06:53:01 INFO - mozscreenshot == 0.1 06:53:01 INFO - mozsystemmonitor == 0.3 06:53:01 INFO - moztest == 0.8 06:53:01 INFO - mozversion == 1.4 06:53:01 INFO - psutil == 3.1.1 06:53:01 INFO - requests == 1.2.3 06:53:01 INFO - wsgiref == 0.1.2 06:53:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:53:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:53:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53:01 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 0x10eeecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eeef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe35b594f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eccad30>, '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 0x10ebf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecbfbe8>, '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.7rgF4xVhV6/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.KZ4IsaeT2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 06:53:01 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 06:53:01 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 06:53:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7rgF4xVhV6/Render', 06:53:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:53:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:53:01 INFO - 'HOME': '/Users/cltbld', 06:53:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:53:01 INFO - 'LOGNAME': 'cltbld', 06:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:53:01 INFO - 'MOZ_NO_REMOTE': '1', 06:53:01 INFO - 'NO_EM_RESTART': '1', 06:53:01 INFO - 'PAGER': '/bin/cat', 06:53:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:53:01 INFO - 'PWD': '/builds/slave/test', 06:53:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:53:01 INFO - 'SHELL': '/bin/bash', 06:53:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZ4IsaeT2M/Listeners', 06:53:01 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 06:53:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:53:01 INFO - 'USER': 'cltbld', 06:53:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:53:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:53:01 INFO - 'XPC_FLAGS': '0x0', 06:53:01 INFO - 'XPC_SERVICE_NAME': '0', 06:53:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:53:01 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)) 06:53:01 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)) 06:53:01 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)) 06:53:01 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)) 06:53:01 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)) 06:53:01 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)) 06:53:01 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)) 06:53:01 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)) 06:53:01 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)) 06:53:01 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)) 06:53:01 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)) 06:53:01 INFO - Cleaning up... 06:53:01 INFO - Return code: 0 06:53:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:53:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:53:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53:01 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 0x10eeecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eeef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe35b594f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eccad30>, '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 0x10ebf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecbfbe8>, '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.7rgF4xVhV6/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.KZ4IsaeT2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 06:53:01 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 06:53:01 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 06:53:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7rgF4xVhV6/Render', 06:53:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:53:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:53:01 INFO - 'HOME': '/Users/cltbld', 06:53:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:53:01 INFO - 'LOGNAME': 'cltbld', 06:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:53:01 INFO - 'MOZ_NO_REMOTE': '1', 06:53:01 INFO - 'NO_EM_RESTART': '1', 06:53:01 INFO - 'PAGER': '/bin/cat', 06:53:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:53:01 INFO - 'PWD': '/builds/slave/test', 06:53:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:53:01 INFO - 'SHELL': '/bin/bash', 06:53:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZ4IsaeT2M/Listeners', 06:53:01 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 06:53:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:53:01 INFO - 'USER': 'cltbld', 06:53:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:53:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:53:01 INFO - 'XPC_FLAGS': '0x0', 06:53:01 INFO - 'XPC_SERVICE_NAME': '0', 06:53:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:53:01 INFO - Cleaning up... 06:53:01 INFO - Return code: 0 06:53:01 INFO - Running post-action listener: _resource_record_post_action 06:53:01 INFO - Running post-action listener: _start_resource_monitoring 06:53:01 INFO - Starting resource monitoring. 06:53:01 INFO - [mozharness: 2017-04-24 13:53:01.816807Z] Finished create-virtualenv step (success) 06:53:01 INFO - [mozharness: 2017-04-24 13:53:01.817720Z] Running install step. 06:53:01 INFO - Running pre-action listener: _resource_record_pre_action 06:53:01 INFO - Running main action method: install 06:53:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:53:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:53:02 INFO - Reading from file tmpfile_stdout 06:53:02 INFO - Detecting whether we're running mozinstall >=1.0... 06:53:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:53:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:53:02 INFO - Reading from file tmpfile_stdout 06:53:02 INFO - Output received: 06:53:02 INFO - Usage: mozinstall [options] installer 06:53:02 INFO - Options: 06:53:02 INFO - -h, --help show this help message and exit 06:53:02 INFO - -d DEST, --destination=DEST 06:53:02 INFO - Directory to install application into. [default: 06:53:02 INFO - "/builds/slave/test"] 06:53:02 INFO - --app=APP Application being installed. [default: firefox] 06:53:02 INFO - mkdir: /builds/slave/test/build/application 06:53:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:53:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 06:53:16 INFO - Reading from file tmpfile_stdout 06:53:16 INFO - Output received: 06:53:16 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 06:53:16 INFO - Running post-action listener: _resource_record_post_action 06:53:16 INFO - [mozharness: 2017-04-24 13:53:16.947272Z] Finished install step (success) 06:53:16 INFO - [mozharness: 2017-04-24 13:53:16.947428Z] Running run-tests step. 06:53:16 INFO - Running pre-action listener: _resource_record_pre_action 06:53:16 INFO - Running main action method: run_tests 06:53:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:53:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:53:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:53:16 INFO - 2017-04-24 06:53:16.961 screenresolution[1632:7556] starting screenresolution argv=screenresolution get 06:53:16 INFO - 2017-04-24 06:53:16.964 screenresolution[1632:7556] Display 0: 1600x1200x32@60 06:53:16 INFO - 2017-04-24 06:53:16.970 screenresolution[1633:7558] starting screenresolution argv=screenresolution list 06:53:16 INFO - Available Modes on Display 0 06:53:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:53:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:53:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:53:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:53:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:53:17 INFO - Intel Iris: 06:53:17 INFO - Chipset Model: Intel Iris 06:53:17 INFO - Type: GPU 06:53:17 INFO - Bus: Built-In 06:53:17 INFO - VRAM (Dynamic, Max): 1536 MB 06:53:17 INFO - Vendor: Intel (0x8086) 06:53:17 INFO - Device ID: 0x0a2e 06:53:17 INFO - Revision ID: 0x0009 06:53:17 INFO - Displays: 06:53:17 INFO - Display: 06:53:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:53:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:53:17 INFO - Display Serial Number: 1600x1200 60 06:53:17 INFO - Main Display: Yes 06:53:17 INFO - Mirror: Off 06:53:17 INFO - Online: Yes 06:53:17 INFO - Rotation: Supported 06:53:17 INFO - Return code: 0 06:53:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:53:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:53:17 INFO - Python 2.7.3 06:53:17 INFO - Return code: 0 06:53:17 INFO - grabbing minidump binary from tooltool 06:53:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:53:17 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 0x10eccad30>, '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 0x10ebf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecbfbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:53:17 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 06:53:17 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 06:53:17 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 06:53:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:53:17 INFO - Return code: 0 06:53:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:53:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:53:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:53:17 INFO - ENV: RUST_BACKTRACE is now 1 06:53:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:53:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:53:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0291', '--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 06:53:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0291 --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 06:53:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7rgF4xVhV6/Render', 06:53:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:53:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:53:17 INFO - 'HOME': '/Users/cltbld', 06:53:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:53:17 INFO - 'LOGNAME': 'cltbld', 06:53:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:53:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:53:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:53:17 INFO - 'MOZ_NO_REMOTE': '1', 06:53:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:53:17 INFO - 'NO_EM_RESTART': '1', 06:53:17 INFO - 'PAGER': '/bin/cat', 06:53:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:53:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:53:17 INFO - 'PWD': '/builds/slave/test', 06:53:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:53:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:53:17 INFO - 'RUST_BACKTRACE': '1', 06:53:17 INFO - 'SHELL': '/bin/bash', 06:53:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZ4IsaeT2M/Listeners', 06:53:17 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 06:53:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:53:17 INFO - 'USER': 'cltbld', 06:53:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:53:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:53:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:53:17 INFO - 'XPC_FLAGS': '0x0', 06:53:17 INFO - 'XPC_SERVICE_NAME': '0', 06:53:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:53:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0291', '--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 06:53:17 INFO - mozversion application_buildid: 20170424060619 06:53:17 INFO - mozversion application_changeset: d5fe7a284cf1c77bc7a258f85f80ba80a051ae0d 06:53:17 INFO - mozversion application_display_name: Firefox 06:53:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:53:17 INFO - mozversion application_name: Firefox 06:53:17 INFO - mozversion application_remotingname: firefox 06:53:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 06:53:17 INFO - mozversion application_vendor: Mozilla 06:53:17 INFO - mozversion application_version: 54.0 06:53:17 INFO - mozversion platform_buildid: 20170424060619 06:53:17 INFO - mozversion platform_changeset: d5fe7a284cf1c77bc7a258f85f80ba80a051ae0d 06:53:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 06:53:17 INFO - mozversion platform_version: 54.0 06:53:17 INFO - using testdate: 1493041997 06:53:17 INFO - actual date: 1493041997 06:53:17 INFO - starting webserver on 'localhost:49236' 06:53:17 INFO - SUITE-START | Running 2 tests 06:53:17 INFO - TEST-START | dromaeo_css 06:53:17 INFO - operating with platform_type : mac_ 06:53:17 INFO - Initialising browser for dromaeo_css test... 06:53:17 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpL5yuIV/profile http://localhost:49236/getInfo.html) 06:53:20 INFO - PROCESS | 1643 | 2017-04-24 06:53:20.337 plugin-container[1646:7742] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 06:53:20 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:53:20 INFO - PROCESS | 1643 | 2017-04-24 06:53:20.337 plugin-container[1646:7742] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c13, name = 'com.apple.CFPasteboardClient' 06:53:20 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:53:20 INFO - PROCESS | 1643 | 2017-04-24 06:53:20.337 plugin-container[1646:7742] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:53:20 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 06:53:20 INFO - PROCESS | 1643 | colorDepth:24 06:53:20 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 06:53:20 INFO - PROCESS | 1643 | __metrics 06:53:20 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 06:53:21 INFO - TEST-INFO | 1643: exit 0 06:53:21 INFO - Browser initialized. 06:53:21 INFO - Running cycle 1/1 for dromaeo_css test... 06:53:21 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpL5yuIV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:53:23 INFO - PROCESS | 1647 | 2017-04-24 06:53:23.407 plugin-container[1648:7869] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 06:53:23 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:53:23 INFO - PROCESS | 1647 | 2017-04-24 06:53:23.407 plugin-container[1648:7869] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 06:53:23 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:53:23 INFO - PROCESS | 1647 | 2017-04-24 06:53:23.407 plugin-container[1648:7869] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:53:33 INFO - PROCESS | 1647 | RSS: Main: 174469120 06:53:33 INFO - PROCESS | 1647 | 06:56:35 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 06:56:37 INFO - PROCESS | 1647 | RSS: Main: 190009344 06:56:37 INFO - PROCESS | 1647 | 06:59:39 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 06:59:40 INFO - PROCESS | 1647 | RSS: Main: 200441856 06:59:40 INFO - PROCESS | 1647 | 07:02:42 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 07:02:43 INFO - PROCESS | 1647 | RSS: Main: 205107200 07:02:43 INFO - PROCESS | 1647 | 07:05:44 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 07:05:45 INFO - PROCESS | 1647 | RSS: Main: 208781312 07:05:45 INFO - PROCESS | 1647 | 07:08:48 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 07:08:48 INFO - PROCESS | 1647 | RSS: Main: 211288064 07:08:48 INFO - PROCESS | 1647 | 07:11:51 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 07:11:51 INFO - PROCESS | 1647 | RSS: Main: 206921728 07:11:51 INFO - PROCESS | 1647 | 07:11:51 INFO - PROCESS | 1647 | __start_tp_report 07:11:51 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 07:11:51 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 07:11:51 INFO - PROCESS | 1647 | |i|pagename|runs| 07:11:51 INFO - PROCESS | 1647 | |0;dojo.html;3135.7770469516877;3596.2807438512295;3602.0994751312173;3602.1534939289245;3607.2605115951214;4878.560929516343;5228.607854410915;5760.222369980052;6115.082737589359;6121.326654068049;5166.914947860944;6086.360932102132;6097.634141951486;6153.138560601508;6174.19735434394;5243.778758099728;5477.780888764445;5502.174673798942;5521.171824226358;5554.50009499145;5492.148716948879;5561.555075593943;5578.525825304833;5580.888382232353;5608.551315894739;5381.54256888163;5426.321709786277;5537.889242215153;5614.438556144394;5642.322921249456;5480.314960629921;5485.753141108659;5499.422560631137;5504.724763761836;5505.476979686923;5368.1679339702405;5437.728113594344;5442.102053161263;5476.91784623231;5479.123340265578;6208.937910620881;6229.127922090911;6234.2830574483605;6242.751449710031;6279.215098112736;6283.748650053993;6396.456301214403;6402.871942561169;6415.101885736;6433.356664333576;6299.622022678654;6300.495960323163;6332.683365831736;6516.185476815398;6543.836404089889;6192.287886892975;6251.937480625181;6256.7810126645345;6273.843153921189;6293.087502312231;6719.664016799238;6726.024726414643;6738.966305168541;6744.898826517606;6747.122874026345;5310.230016647642;6382.106505089291;6394;6398.456131228804;6399.712012959428;6315.147455093595;6351.587146835441;6352.95176295906;6386.744530218833;6420.0690899819265;6334.746610135543;6432.485401167895;6445.323241059621;6447.1296374989715;6448.548601597937;5514.779408823602;6311.305756366796;6371.23545174582;6406.135171751754;6448.548601597843;5039.319691841628;5067.873303167376;5139.460356662496;5215.087359712035;5220.112580861262;4493.393391892118;4563.224251877189;4631;4669.906601868011;4690.95309046912;6195.411435913581;6251.499880009588;6262.5616206866;6276.560640755195;6303.621782693053;6506.804795856132;6576.967115164393;6588.604683718992;6601.438877695354;6644.235912869985;6138.508919286446;6146.494108943338;6198.380161983766;6225.28409232935;6298.055291706189;5747.5689323300085;5748.770049197985;5756.740946657411;5778.046622307273;5782.595218334676;6756.290589488033;6786.524943254023;6802.863942721118;6817.625030623363;6855.520113592001;6245.0632405139595;6310.337414571404;6436;6578.342165783383;6596.90104648421;6724.4284235840505;6745.662716864235;6773.491988101011;6784.3554862288;6806.829829254309;6382.32985536512;6473.935260647521;6482.837929051811;6486.772962946275;6496.155499784998;5141.408737995179;6154.3845615438095;6272.8118156455375;6316.115743795965;6546.607203567801;6260;6294.622322660655;6361.427471527585;6374.426301632875;6384.170057892445;5231.372235331784;6339.144215530844;6355.713992870239;6361.236651601837;6366.267879193952;6121.479674227562;6202.72087756043;6261.5616906816085;6310.652914089769;6318.336574659595;6190.628562286277;6260.593061450991;6271.874562508816;6275.937240627718;6289.842753931189;6290.716917738621;6306.495480361652;6309.148264984168;6309.211348581427;6318.304986451395 07:11:51 INFO - PROCESS | 1647 | |1;ext.html;753.6307209467359;824.5835852894287;843.6414523827374;935.5602866652671;978.2320878110685;40242;41383;42538;42540.936476588104;42733.78633106838;40609.390859137064;40792.796036019856;40808.18383632325;40854.79572602133;40924.38613420801;8289.129641387657;8443.662253509852;11335.943320283428;11610.071194304477;11864.406779660982;11480.827787583152;11520.02079823216;11593.30440173588;11598.884011159907;11618.535258589647;11557.711057223552;11605;11611.47748351322;11618.767624647502;11619.186656934018;9609.663661771805;11498.195126341201;11555.826662600068;11571.190016698834;11593.072554195729;11185.888141118567;11228.49471773764;11295.322280663186;11319.547218111265;11323;34713;34832;34942.825285873405;34992.65007349919;35015.824920875486;3930.508686414198;3930.8231129599235;3956.8417263309434;3962.0689138052535;4036.9394459083137;26516;26721.866390668118;26833.0608428706;28124.437511249864;28248.71751282502;3209.133533945845;3262.13553408347;3280.70473657372;3310.5034244863223;3417.333619944112;322326;326740;366909;369164.15417922736;418248.999999997;46325.07349852984;46388.536114638424;46462.30306545404;46544.534554654696;46566.53433465622;40106.79946600307;47734.28398574024;48055.759721201866;48357;48402.03195936062;13892.583222503341;13967.930160349337;14036.789448158286;14113.364898579588;14120.50578229757;12427.565035223724;12544.996400288137;12551.874481255254;12556;12573.119881608383;11672.774358692215;12231.755364892651;12251.75496490083;12287.324197169062;12329.198602090835;4753.83361582343;4756.405449318835;4882.487338829372;5695.515881150149;5730.828075157752;1131.8981291683624;1189.2210602055668;1189.52419032386;1200.5137919142587;1234.975300493985;28227.15318540447;28327.57508637372;28688;28753.56869646957;28895;1047.554789214596;1071.4428497181423;1107.5901916290863;1117.463617463622;1344.519361777519;14786.334614942354;15143.621409464631;16872.987620742795;17165.055921924413;17358.305667773147;486209;489133;489568;490200;490275;61686.38313616896;62006;62432.37567624266;62450.37549624446;62489.37510624836;1533.4173014254511;1566.8981516201638;1604.4224079331432;1604.7271963765954;1610.315615863277;25333;29573.852130739207;31195.908143214878;31202.687973120886;31862.362752745277;51971;52077.7396113017;52217.47782522126;52230.47769522408;52242;472681;473779.6311018423;473821.63089184335;473970;474404;34034;34544.96365109051;36362.090947726516;37483;37513.43729844109;41132.79433602813;41527.377089344285;42199.367009494264;42599.93500162397;42776.14447711091;28832.98169128141;29492.705072948997;30233.69766302309;30290.69709302967;30299.848500757354;28585;29153.41693166167;30064.849675752357;30071;30092.54861177128;35080.298394032485;35126.64873351234;35221.471677925365;35493;35541.64458355383;22698.659520107543;29428.969986050386;29676.55485167681;29697.406051879272;30337.393252135276;23627.40931476727;23966.40083997914;24043.51912961766;24136.75863241345;24309.878450608343 07:11:51 INFO - PROCESS | 1647 | |2;jquery.html;426.80153728516234;437.9255526560485;439.6765735137238;447.99328010079836;450.9852831129958;20433.386998390055;21502.89248553757;22453.775462245372;22674.54650906985;22797.886010569946;23489;23507.412314692097;23928.04287828489;24149.75850241497;24272;6877.965610171942;6969.756058537965;7129.429645628337;7248.927510724878;7251.818704532402;6980.9650951745425;7080.53976491529;7102.573845569256;7114.64426778659;7131.536450130725;6753.493487988396;7052.576845389267;7059.117610298713;7115.146182458112;7120.715171393138;6878.209005964303;6910.861782764317;7069.858602827941;7085.787426377217;7153.141623005246;6874.8625027499675;6913.5506192097855;6917.169939607229;6919.238883722786;6920.377166055079;28240.576391354145;28460.861565537354;28550.428991420056;28806.279843004093;28910.710892891013;3069.41681080594;3091.180837078176;3109.782315237934;3462.394081035834;3613.223157021253;16555.83444165555;17515.547823304747;17615.207315670825;18175.363862264756;18224.179911903997;1911.4934542346289;2066.9483262918548;2091.8326533877253;2104.779228047729;2192.7916847899423;133516;135860.32069839587;143389;144274;145441.8183727245;18194.818051819577;21868.23461178883;23021.309360719206;23036;23370.64944025841;31095.68904310973;37690.811545942095;38490.80754596209;38977.80511097427;38987.6101238984;10533.7886143093;10720.142388608892;11089.778204435866;11302.37836918961;11341.886581134084;7234.493585449073;7452.919326697598;8040.919590804017;8924.196822285896;9079.68221112258;7876.960615197002;9169.908300917039;10846.94576527128;11315.94342028301;11396.202265841312;4194.538600753915;4557.384753058361;4702.719181197117;4715.269133284346;4762.52374762521;945.4752612300102;1017.85999680357;1072.892710728921;1125.139268459629;1126.7352172239516;20630.690539641917;23198.65202021971;23313.533729325318;23469.41326466818;23474.943627536803;1004.8894621591619;1031.3090229546071;1103.9889601104046;1121.1367247219596;1143.9256448330998;9676.951615241878;12111.757764844653;12143.635690929286;12371;12383.876161238271;719529;729896;751934;787129;793521;136281.9557706635;162343;172767;172897.13551432162;180163;1121.7924683933497;1357.8234829472106;1394.525861207174;1395.8534353892899;1396.4204854985067;20871.269505567234;20957.89521052385;21231.815227715968;21473;21488.92555372194;54648;60360.69819650874;66831.33168668445;66976.33023669702;67279.66360168168;93075.06924930665;93589.5320523393;93789.5310523443;93807.53096234475;95774.52112739392;11763.353015584225;11845.289282643069;11903.940480297542;12001.699532628249;12069.57756478519;11758.529658813552;12897.226166430044;13040.478380864659;13066.281354525592;13325.067245293125;10862.34825910448;11187.049100826522;11254.88745112571;11314.377709226072;11511.884881151082;10527.631532896312;10616;10681.252312338062;10802.405867677353;10858.837117443087;11605.825912611148;11752.23610465317;11772.352520611448;11877.643670689893;11962.162648614514;11810.940945295219;11816.231944923553;11949.462274197856;12019.03847692165;12114.394280285775;11264.380459074502;11331.773364532828;11507;11606.767864642828;11635.650930472098 07:11:51 INFO - PROCESS | 1647 | |3;mootools.html;1629.3808352825924;1661.5596866830292;1663.9251233694483;1666.3751093340004;1692.2723229011524;11378.943105284472;11598.536058557647;12004.979576735966;12175.756484870308;12204.877951220486;11376.488058037388;11565.942170289136;11569.305841649508;11575.53697852085;11594.362310072942;2455.4352498925214;2489.7759201671847;2503.7245902950663;2505.5364757519824;2512.660790793238;2467.506498700267;2487.6268559716004;2505.1732928133674;2516.1822407717473;2522.6846644169477;2480.293116461809;2491.289982355029;2500.6749122614124;2525.1666949906485;2525.229804909502;2077.241806740544;2458.4222707663685;2481.962770558443;2484.714257860342;2500.94998100037;2460.4709987352803;2479.2686157583557;2480.677511923456;2490.7384724604017;2494.625806129077;11151.107911367153;11251.043661288719;11290.661280161608;11329.546818127264;11340.319580825095;5994.910076348859;6158.84602884927;6167.814965551041;6169.2288463942;6241.251049873998;3667.669909708139;3770.5286839145106;3785.0726571989762;3818.1409182933894;3879.398693202558;1276.6935935375536;1918.5587314917507;1968.468513501361;1988.3040935672543;2036.7250421193098;14234.92882535591;14528.927355363261;15430.228488575638;16293.266802993894;16445.177741112897;4655.720656760537;4886.413630364379;4997.425296090923;5175.301334319894;5231.346081739783;13579.86420135786;13603.115797473132;13642.658933526742;13659.863401366058;13695.452181912611;6212.47193988507;6213.440790328802;6275.1842260506755;6275.215598050244;6279.497640188774;5560.499555039985;5643.35101463337;5653.604247702621;5673.092305231144;5741.741621626953;4300.376445415397;4323.308270676676;4396.978015109968;4408.118376324684;4444.466664000341;1182.2256422043574;1210.425048102162;1224.3510939202238;1224.4428784902848;1229.5819421396588;863.7797361672733;945.4374647084974;952.9761755956157;968.8159249742527;1007.3603261928581;6815.352541508549;6995.825104372431;7219.205887352492;7228.746993855295;7240.348368646847;1009.7728011197422;1049.8399268807857;1088.6026600290995;1099.1042300525046;1140.3670962615663;4315.741055536678;4438.400815889878;4483.923858274056;4514.390557274792;4636;10675.89324106749;11731.648050558497;13708.794368084487;14298.142111473346;14326.426942922375;12121.333326666942;13890.58328250154;14406.783898241534;14414.495492657707;14424.495142670166;8928.99999999987;10239.948800255952;10477.738056548495;10486;10513.684589462327;6644.568103073285;7029.578225306498;7189.496735228484;7209.423246140192;7294.63526823646;11919.225250358699;12029.15795894279;12246.326452044867;12389.318587477415;12393.938030309791;12544.372781361079;12586.937065314614;12671.429785659851;12761.042921781089;12957.028222883133;9459.952700236454;9582.377145485521;9717.076877696607;9743.415395076318;9747.756306092404;10416.427096509764;10507.737306567396;10574.947125264325;10646.946765266433;10664.626738064131;8913.420627659181;8980.191782739585;9022.774430639287;9024.458532488072;9201.76995575116;8827.602757876039;8873.423227490193;8876.423032502867;8962.283017358464;8972.41679290844;8064.959675201587;8219.383546233938;8237.588120593826;8246.38152138604;8250.876236856571;6081.908771368521;6085.604435711664;6118.847028824315;6231.065340198825;6287.30839607652;6078.756849725962;6165.630062196283;6232.314445410911;6284.151639528605;6306.527010474325 07:11:51 INFO - PROCESS | 1647 | |4;prototype.html;398.48794299325374;401.4660501532961;402.8799936177427;405.7666841566098;407.57815660791067;4333.068390296085;4500.324951257313;4710.563278200156;5169.224616307556;5218.347706536683;5051.345827074153;5180.430152683201;5208.427073021964;5216.556592689626;5305.734713264341;3647.598764135943;4144.626983571478;4155.210510003105;4163.396307535406;4169.854055108071;3501.9299614007705;3865.1833638190005;3942.9354074399894;4169.3954376615375;4242.809073591696;3538.716902647795;3570.0539357070397;3665.047087757189;4196.412502249687;4226.175895700339;3674;3825.9234815303817;3921.117748506592;3948.091938854065;4171.478565179353;3007.7293043626178;3543.326767914114;3723.4733759158885;3724.3426675129576;3749.7562658427114;4322.789618906711;4449.26587113126;4852.684575502581;5180.818671346522;5185.714785686786;3875.283072631544;3971.6624086952643;4782.258749893772;4839.927401088987;4883.291922671193;5604;5618.606697531174;5641.605087643867;5655.095184770417;5710.685912274823;3552.6980206682465;3773.6980142375423;4156.521999970012;4157.355609880473;4177.582241775828;5204.791808327663;5355.3305836770405;5732.22614946981;5774.8161626866395;5967.030879809652;3475.4960530722906;3875.6318149775725;3993.4625169309443;4202.931859742405;4223.303154979455;3755.493008443825;4219.852305169305;4270.7864606769535;4273.957260427418;4275.679324050709;4257.978085259578;4291.03451723366;4400.647948164139;4858.562729354375;4876.536729010738;4980.950190498121;5578.665280083208;5628.04323265046;5656.37779844225;5693.4875861172695;1734.6530693861278;1748.8950662960262;1758.4613463219682;1773.6630040292303;1797.173300281859;1225.5526732742662;1240.1752834365227;1310.7575098606787;1360.2246719369864;1427.5431861804122;4743.075100355398;5100.030994111181;5177.559907407913;5287.048331300402;5457.454254574511;5856.590038697335;5890.941090589039;5895.199780104416;5900.734466948998;5929.495992840657;4717.5940270462315;4964.702117872867;5098.1588037973315;5135.6148288878485;5493.406911995557;2754.9724502755116;2919.8320671731476;3027.197792584923;3036.375539086578;3054.740347070524;4870.537298956593;5448.9727551362785;5617.960692255841;5870.5637160578735;6037.6679282639;5432.158015507603;5524.889502209933;5592.790847615251;5968.880622387528;6039.63762174271;5226.634135610547;5501.477359650827;5626.718664066862;5702.429757024347;5953.702314884238;5718.885622287614;5814.651120932757;5904.645721256738;5904.844731672994;5907.881842363215;4868.634852386156;5149.382074151126;5642.463965923231;5688;5757.913631295618;5825.592208545361;5894.734736936763;6083.908741368971;6133.877322453615;6197.628142311476;4250.404943307877;4367.061081867414;4681.274402467623;4689.827543114221;4706.270528068155;4719.551642593948;4751.524847515221;5404.081306178038;5795.406263277632;5825.912611310919;5221.895562088813;5607.859803504945;5637.46444087811;5694.629849059798;5719.227904233042;4672.57946784791;4882.804687812448;5249.6062795289745;5624.521915637217;5677.630953987978;5081.949180508148;5490.121580547093;5528.834134975958;5570.693611851387;5578.748956297058;5120.48795120485;5395.433479484597;5707.714614269353;5729.931748458641;5732.570057245806;4176.895577610584;4809.44691360498;4818.951945423251;4902.656814022984;5062.949505450299 07:11:51 INFO - PROCESS | 1647 | |5;yui.html;754.1139161485254;762.26441483968;794.4359504751626;894.3381897395927;902.3638334973842;16490.587735306624;17163.313467461285;17379.565510862234;17442.215100320485;18084;15023.248837558105;16385.754213686774;16443.753343699824;16757.581060473494;16782.83217167828;971.2569884038699;987.1017374189489;991.3011327014474;993.7776534862124;994.9651762188325;944.6552008516876;976.3214565876718;1015.9695209143738;1020.8928062553437;1020.9642662506814;1077.030672394847;1085;1091.3506463654153;1091.956321747129;1093.316677076827;1071.6356438810842;1082.1505118482032;1083.2796190533286;1087.2932593814005;1089.4008295437518;792.4928046050533;795.2762985683;799.3645052183529;800.0599295827379;801.2148094867063;17043.57391065221;17073.914630426767;17195.39816106443;17916.55208619782;18188.818111818844;14756.557303280919;14783.408663653441;14859.257037148207;14859.70280594382;15026.17356045428;11827.467763950557;12169.513219471211;12236.143469957107;12286.569970051005;12321.815172772416;3781.489498917666;3848.5574158971517;3851.480050193216;3904.3557812960826;4036.1725846201753;115006;121064.39467802604;121681.39159304235;122319;122358;11196.38419886914;11326.943365283285;11424.657260282205;11610.24533405326;11981.64055078349;23907.760922390902;24179.51640967206;24199.274021779376;24430.389240269134;24503.50992980166;7998.960135182389;8045;8110;8214.876776848352;8378.79053023667;3938.3501722215515;4082.1631565528996;4419.867403977886;4557;4575.95424045762;3635.327464419091;3701.7038636908983;3751.1934933989323;3844.154286057063;4029.1135950090934;638.1257676982564;719.5287086958051;723.1177962885272;725.9092613423322;737.2958824322759;632.867097909435;712.2023333866103;720.7873677265219;749.9100107986967;766.9689407229912;10362.533685984148;10429.165666746641;10485.737856553647;10730.517126729315;10773.569057237622;689.348565605498;708.6208878250214;722.3282347416953;747.1482509938675;749.82753966588;5246.895062098813;5271.446498117719;5386;5389.811356602579;5394.32570928634;106322.93677063285;106831;108757;111813;112138;73265;89779;93453;93538.06461935429;94982;52923;84549.15450845413;86226;86366;86512.13487865166;9763.902360976299;9992.750181245527;10221.744456388353;10322.845157322496;10325.432101234504;49560;53662.46337536731;53697;54975.450245498636;55558;69051;70080;70154;72045;72247;11501.82747258775;11578.536858525564;12341.938290308492;12710.491580336682;12748.235105893677;15112;15700.607484812972;15917.761233581274;15921.124338161513;16106.114163721108;10228.181745460197;12004.459799308877;14079.718405632035;14756.926215369283;15179.924100379427;11247.66257012291;14238.471706112841;14426.350814213596;14608.78086828677;14790;14262.286885655883;14393.424263029361;14485.275736212936;15027.849721502645;15284.159371234244;9811.354412879598;10153.340032897839;10247.590096396061;10522.789544209227;10548;9794.853077203705;10191.388516689023;10727.356358618508;11790.528378864748;12059.57791477294 07:11:51 INFO - PROCESS | 1647 | __end_tp_report 07:11:51 INFO - PROCESS | 1647 | __start_cc_report 07:11:51 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,395 07:11:51 INFO - PROCESS | 1647 | __end_cc_report 07:11:51 INFO - PROCESS | 1647 | __startTimestamp1493043111549__endTimestamp 07:11:51 INFO - PROCESS | 1647 | 07:11:51 INFO - PROCESS | 1647 | ------- Summary: start ------- 07:11:51 INFO - PROCESS | 1647 | Number of tests: 6 07:11:51 INFO - PROCESS | 1647 | 07:11:51 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:6003.40 Median:6272.81 stddev:686.15 (10.9%) stddev-sans-first:650.33 07:11:51 INFO - PROCESS | 1647 | Values: 3135.8 3596.3 3602.1 3602.2 3607.3 4878.6 5228.6 5760.2 6115.1 6121.3 5166.9 6086.4 6097.6 6153.1 6174.2 5243.8 5477.8 5502.2 5521.2 5554.5 5492.1 5561.6 5578.5 5580.9 5608.6 5381.5 5426.3 5537.9 5614.4 5642.3 5480.3 5485.8 5499.4 5504.7 5505.5 5368.2 5437.7 5442.1 5476.9 5479.1 6208.9 6229.1 6234.3 6242.8 6279.2 6283.7 6396.5 6402.9 6415.1 6433.4 6299.6 6300.5 6332.7 6516.2 6543.8 6192.3 6251.9 6256.8 6273.8 6293.1 6719.7 6726.0 6739.0 6744.9 6747.1 5310.2 6382.1 6394.0 6398.5 6399.7 6315.1 6351.6 6353.0 6386.7 6420.1 6334.7 6432.5 6445.3 6447.1 6448.5 5514.8 6311.3 6371.2 6406.1 6448.5 5039.3 5067.9 5139.5 5215.1 5220.1 4493.4 4563.2 4631.0 4669.9 4691.0 6195.4 6251.5 6262.6 6276.6 6303.6 6506.8 6577.0 6588.6 6601.4 6644.2 6138.5 6146.5 6198.4 6225.3 6298.1 5747.6 5748.8 5756.7 5778.0 5782.6 6756.3 6786.5 6802.9 6817.6 6855.5 6245.1 6310.3 6436.0 6578.3 6596.9 6724.4 6745.7 6773.5 6784.4 6806.8 6382.3 6473.9 6482.8 6486.8 6496.2 5141.4 6154.4 6272.8 6316.1 6546.6 6260.0 6294.6 6361.4 6374.4 6384.2 5231.4 6339.1 6355.7 6361.2 6366.3 6121.5 6202.7 6261.6 6310.7 6318.3 6190.6 6260.6 6271.9 6275.9 6289.8 6290.7 6306.5 6309.1 6309.2 6318.3 07:11:51 INFO - PROCESS | 1647 | 07:11:51 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:57981.75 Median:31532.53 stddev:118278.27 (375.1%) stddev-sans-first:118532.03 07:11:51 INFO - PROCESS | 1647 | Values: 753.6 824.6 843.6 935.6 978.2 40242.0 41383.0 42538.0 42540.9 42733.8 40609.4 40792.8 40808.2 40854.8 40924.4 8289.1 8443.7 11335.9 11610.1 11864.4 11480.8 11520.0 11593.3 11598.9 11618.5 11557.7 11605.0 11611.5 11618.8 11619.2 9609.7 11498.2 11555.8 11571.2 11593.1 11185.9 11228.5 11295.3 11319.5 11323.0 34713.0 34832.0 34942.8 34992.7 35015.8 3930.5 3930.8 3956.8 3962.1 4036.9 26516.0 26721.9 26833.1 28124.4 28248.7 3209.1 3262.1 3280.7 3310.5 3417.3 322326.0 326740.0 366909.0 369164.2 418249.0 46325.1 46388.5 46462.3 46544.5 46566.5 40106.8 47734.3 48055.8 48357.0 48402.0 13892.6 13967.9 14036.8 14113.4 14120.5 12427.6 12545.0 12551.9 12556.0 12573.1 11672.8 12231.8 12251.8 12287.3 12329.2 4753.8 4756.4 4882.5 5695.5 5730.8 1131.9 1189.2 1189.5 1200.5 1235.0 28227.2 28327.6 28688.0 28753.6 28895.0 1047.6 1071.4 1107.6 1117.5 1344.5 14786.3 15143.6 16873.0 17165.1 17358.3 486209.0 489133.0 489568.0 490200.0 490275.0 61686.4 62006.0 62432.4 62450.4 62489.4 1533.4 1566.9 1604.4 1604.7 1610.3 25333.0 29573.9 31195.9 31202.7 31862.4 51971.0 52077.7 52217.5 52230.5 52242.0 472681.0 473779.6 473821.6 473970.0 474404.0 34034.0 34545.0 36362.1 37483.0 37513.4 41132.8 41527.4 42199.4 42599.9 42776.1 28833.0 29492.7 30233.7 30290.7 30299.8 28585.0 29153.4 30064.8 30071.0 30092.5 35080.3 35126.6 35221.5 35493.0 35541.6 22698.7 29429.0 29676.6 29697.4 30337.4 23627.4 23966.4 24043.5 24136.8 24309.9 07:11:51 INFO - PROCESS | 1647 | 07:11:51 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:44051.95 Median:12323.28 stddev:126171.52 (1023.8%) stddev-sans-first:126482.94 07:11:51 INFO - PROCESS | 1647 | Values: 426.8 437.9 439.7 448.0 451.0 20433.4 21502.9 22453.8 22674.5 22797.9 23489.0 23507.4 23928.0 24149.8 24272.0 6878.0 6969.8 7129.4 7248.9 7251.8 6981.0 7080.5 7102.6 7114.6 7131.5 6753.5 7052.6 7059.1 7115.1 7120.7 6878.2 6910.9 7069.9 7085.8 7153.1 6874.9 6913.6 6917.2 6919.2 6920.4 28240.6 28460.9 28550.4 28806.3 28910.7 3069.4 3091.2 3109.8 3462.4 3613.2 16555.8 17515.5 17615.2 18175.4 18224.2 1911.5 2066.9 2091.8 2104.8 2192.8 133516.0 135860.3 143389.0 144274.0 145441.8 18194.8 21868.2 23021.3 23036.0 23370.6 31095.7 37690.8 38490.8 38977.8 38987.6 10533.8 10720.1 11089.8 11302.4 11341.9 7234.5 7452.9 8040.9 8924.2 9079.7 7877.0 9169.9 10846.9 11315.9 11396.2 4194.5 4557.4 4702.7 4715.3 4762.5 945.5 1017.9 1072.9 1125.1 1126.7 20630.7 23198.7 23313.5 23469.4 23474.9 1004.9 1031.3 1104.0 1121.1 1143.9 9677.0 12111.8 12143.6 12371.0 12383.9 719529.0 729896.0 751934.0 787129.0 793521.0 136282.0 162343.0 172767.0 172897.1 180163.0 1121.8 1357.8 1394.5 1395.9 1396.4 20871.3 20957.9 21231.8 21473.0 21488.9 54648.0 60360.7 66831.3 66976.3 67279.7 93075.1 93589.5 93789.5 93807.5 95774.5 11763.4 11845.3 11903.9 12001.7 12069.6 11758.5 12897.2 13040.5 13066.3 13325.1 10862.3 11187.0 11254.9 11314.4 11511.9 10527.6 10616.0 10681.3 10802.4 10858.8 11605.8 11752.2 11772.4 11877.6 11962.2 11810.9 11816.2 11949.5 12019.0 12114.4 11264.4 11331.8 11507.0 11606.8 11635.7 07:11:51 INFO - PROCESS | 1647 | 07:11:51 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:7034.03 Median:2500.81 stddev:4283.38 (171.3%) stddev-sans-first:4276.15 07:11:51 INFO - PROCESS | 1647 | Values: 1629.4 1661.6 1663.9 1666.4 1692.3 11378.9 11598.5 12005.0 12175.8 12204.9 11376.5 11565.9 11569.3 11575.5 11594.4 2455.4 2489.8 2503.7 2505.5 2512.7 2467.5 2487.6 2505.2 2516.2 2522.7 2480.3 2491.3 2500.7 2525.2 2525.2 2077.2 2458.4 2482.0 2484.7 2500.9 2460.5 2479.3 2480.7 2490.7 2494.6 11151.1 11251.0 11290.7 11329.5 11340.3 5994.9 6158.8 6167.8 6169.2 6241.3 3667.7 3770.5 3785.1 3818.1 3879.4 1276.7 1918.6 1968.5 1988.3 2036.7 14234.9 14528.9 15430.2 16293.3 16445.2 4655.7 4886.4 4997.4 5175.3 5231.3 13579.9 13603.1 13642.7 13659.9 13695.5 6212.5 6213.4 6275.2 6275.2 6279.5 5560.5 5643.4 5653.6 5673.1 5741.7 4300.4 4323.3 4397.0 4408.1 4444.5 1182.2 1210.4 1224.4 1224.4 1229.6 863.8 945.4 953.0 968.8 1007.4 6815.4 6995.8 7219.2 7228.7 7240.3 1009.8 1049.8 1088.6 1099.1 1140.4 4315.7 4438.4 4483.9 4514.4 4636.0 10675.9 11731.6 13708.8 14298.1 14326.4 12121.3 13890.6 14406.8 14414.5 14424.5 8929.0 10239.9 10477.7 10486.0 10513.7 6644.6 7029.6 7189.5 7209.4 7294.6 11919.2 12029.2 12246.3 12389.3 12393.9 12544.4 12586.9 12671.4 12761.0 12957.0 9460.0 9582.4 9717.1 9743.4 9747.8 10416.4 10507.7 10574.9 10646.9 10664.6 8913.4 8980.2 9022.8 9024.5 9201.8 8827.6 8873.4 8876.4 8962.3 8972.4 8065.0 8219.4 8237.6 8246.4 8250.9 6081.9 6085.6 6118.8 6231.1 6287.3 6078.8 6165.6 6232.3 6284.2 6306.5 07:11:51 INFO - PROCESS | 1647 | 07:11:51 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4584.59 Median:4883.05 stddev:1315.51 (26.9%) stddev-sans-first:1281.13 07:11:51 INFO - PROCESS | 1647 | Values: 398.5 401.5 402.9 405.8 407.6 4333.1 4500.3 4710.6 5169.2 5218.3 5051.3 5180.4 5208.4 5216.6 5305.7 3647.6 4144.6 4155.2 4163.4 4169.9 3501.9 3865.2 3942.9 4169.4 4242.8 3538.7 3570.1 3665.0 4196.4 4226.2 3674.0 3825.9 3921.1 3948.1 4171.5 3007.7 3543.3 3723.5 3724.3 3749.8 4322.8 4449.3 4852.7 5180.8 5185.7 3875.3 3971.7 4782.3 4839.9 4883.3 5604.0 5618.6 5641.6 5655.1 5710.7 3552.7 3773.7 4156.5 4157.4 4177.6 5204.8 5355.3 5732.2 5774.8 5967.0 3475.5 3875.6 3993.5 4202.9 4223.3 3755.5 4219.9 4270.8 4274.0 4275.7 4258.0 4291.0 4400.6 4858.6 4876.5 4981.0 5578.7 5628.0 5656.4 5693.5 1734.7 1748.9 1758.5 1773.7 1797.2 1225.6 1240.2 1310.8 1360.2 1427.5 4743.1 5100.0 5177.6 5287.0 5457.5 5856.6 5890.9 5895.2 5900.7 5929.5 4717.6 4964.7 5098.2 5135.6 5493.4 2755.0 2919.8 3027.2 3036.4 3054.7 4870.5 5449.0 5618.0 5870.6 6037.7 5432.2 5524.9 5592.8 5968.9 6039.6 5226.6 5501.5 5626.7 5702.4 5953.7 5718.9 5814.7 5904.6 5904.8 5907.9 4868.6 5149.4 5642.5 5688.0 5757.9 5825.6 5894.7 6083.9 6133.9 6197.6 4250.4 4367.1 4681.3 4689.8 4706.3 4719.6 4751.5 5404.1 5795.4 5825.9 5221.9 5607.9 5637.5 5694.6 5719.2 4672.6 4882.8 5249.6 5624.5 5677.6 5081.9 5490.1 5528.8 5570.7 5578.7 5120.5 5395.4 5707.7 5729.9 5732.6 4176.9 4809.4 4819.0 4902.7 5062.9 07:11:51 INFO - PROCESS | 1647 | 07:11:51 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:21694.90 Median:17679.38 stddev:31428.05 (177.8%) stddev-sans-first:31476.88 07:11:51 INFO - PROCESS | 1647 | Values: 754.1 762.3 794.4 894.3 902.4 16490.6 17163.3 17379.6 17442.2 18084.0 15023.2 16385.8 16443.8 16757.6 16782.8 971.3 987.1 991.3 993.8 995.0 944.7 976.3 1016.0 1020.9 1021.0 1077.0 1085.0 1091.4 1092.0 1093.3 1071.6 1082.2 1083.3 1087.3 1089.4 792.5 795.3 799.4 800.1 801.2 17043.6 17073.9 17195.4 17916.6 18188.8 14756.6 14783.4 14859.3 14859.7 15026.2 11827.5 12169.5 12236.1 12286.6 12321.8 3781.5 3848.6 3851.5 3904.4 4036.2 115006.0 121064.4 121681.4 122319.0 122358.0 11196.4 11326.9 11424.7 11610.2 11981.6 23907.8 24179.5 24199.3 24430.4 24503.5 7999.0 8045.0 8110.0 8214.9 8378.8 3938.4 4082.2 4419.9 4557.0 4576.0 3635.3 3701.7 3751.2 3844.2 4029.1 638.1 719.5 723.1 725.9 737.3 632.9 712.2 720.8 749.9 767.0 10362.5 10429.2 10485.7 10730.5 10773.6 689.3 708.6 722.3 747.1 749.8 5246.9 5271.4 5386.0 5389.8 5394.3 106322.9 106831.0 108757.0 111813.0 112138.0 73265.0 89779.0 93453.0 93538.1 94982.0 52923.0 84549.2 86226.0 86366.0 86512.1 9763.9 9992.8 10221.7 10322.8 10325.4 49560.0 53662.5 53697.0 54975.5 55558.0 69051.0 70080.0 70154.0 72045.0 72247.0 11501.8 11578.5 12341.9 12710.5 12748.2 15112.0 15700.6 15917.8 15921.1 16106.1 10228.2 12004.5 14079.7 14756.9 15179.9 11247.7 14238.5 14426.4 14608.8 14790.0 14262.3 14393.4 14485.3 15027.8 15284.2 9811.4 10153.3 10247.6 10522.8 10548.0 9794.9 10191.4 10727.4 11790.5 12059.6 07:11:51 INFO - PROCESS | 1647 | -------- Summary: end -------- 07:11:51 INFO - PROCESS | 1647 | 07:11:52 INFO - TEST-INFO | 1647: exit 0 07:11:52 INFO - TEST-OK | dromaeo_css | took 1114545ms 07:11:52 INFO - TEST-START | kraken 07:11:52 INFO - operating with platform_type : mac_ 07:11:52 INFO - Initialising browser for kraken test... 07:11:52 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpwgkea7/profile http://localhost:49236/getInfo.html) 07:11:54 INFO - PROCESS | 1656 | 2017-04-24 07:11:54.467 plugin-container[1658:10189] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 07:11:54 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:11:54 INFO - PROCESS | 1656 | 2017-04-24 07:11:54.467 plugin-container[1658:10189] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 07:11:54 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:11:54 INFO - PROCESS | 1656 | 2017-04-24 07:11:54.467 plugin-container[1658:10189] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:11:54 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 07:11:54 INFO - PROCESS | 1656 | colorDepth:24 07:11:54 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 07:11:54 INFO - PROCESS | 1656 | __metrics 07:11:54 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 07:11:55 INFO - TEST-INFO | 1656: exit 0 07:11:55 INFO - Browser initialized. 07:11:55 INFO - Running cycle 1/1 for kraken test... 07:11:55 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpwgkea7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:11:57 INFO - PROCESS | 1659 | 2017-04-24 07:11:57.641 plugin-container[1660:10323] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 07:11:57 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:11:57 INFO - PROCESS | 1659 | 2017-04-24 07:11:57.641 plugin-container[1660:10323] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451f, name = 'com.apple.CFPasteboardClient' 07:11:57 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:11:57 INFO - PROCESS | 1659 | 2017-04-24 07:11:57.641 plugin-container[1660:10323] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:12:07 INFO - PROCESS | 1659 | RSS: Main: 174489600 07:12:07 INFO - PROCESS | 1659 | 07:12:10 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 07:12:11 INFO - PROCESS | 1659 | RSS: Main: 188190720 07:12:11 INFO - PROCESS | 1659 | 07:12:13 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 07:12:14 INFO - PROCESS | 1659 | RSS: Main: 196145152 07:12:14 INFO - PROCESS | 1659 | 07:12:17 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 07:12:18 INFO - PROCESS | 1659 | RSS: Main: 199921664 07:12:18 INFO - PROCESS | 1659 | 07:12:20 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 07:12:20 INFO - PROCESS | 1659 | RSS: Main: 200511488 07:12:20 INFO - PROCESS | 1659 | 07:12:22 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:12:23 INFO - PROCESS | 1659 | RSS: Main: 200937472 07:12:23 INFO - PROCESS | 1659 | 07:12:26 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 07:12:26 INFO - PROCESS | 1659 | RSS: Main: 205496320 07:12:26 INFO - PROCESS | 1659 | 07:12:29 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 07:12:30 INFO - PROCESS | 1659 | RSS: Main: 205545472 07:12:30 INFO - PROCESS | 1659 | 07:12:33 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 07:12:34 INFO - PROCESS | 1659 | RSS: Main: 204972032 07:12:34 INFO - PROCESS | 1659 | 07:12:35 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:12:36 INFO - PROCESS | 1659 | RSS: Main: 204472320 07:12:36 INFO - PROCESS | 1659 | 07:12:38 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:12:39 INFO - PROCESS | 1659 | RSS: Main: 204546048 07:12:39 INFO - PROCESS | 1659 | 07:12:41 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:12:42 INFO - PROCESS | 1659 | RSS: Main: 204599296 07:12:42 INFO - PROCESS | 1659 | 07:12:44 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:12:45 INFO - PROCESS | 1659 | RSS: Main: 204865536 07:12:45 INFO - PROCESS | 1659 | 07:12:48 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:12:49 INFO - PROCESS | 1659 | RSS: Main: 205012992 07:12:49 INFO - PROCESS | 1659 | 07:12:50 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 07:12:51 INFO - PROCESS | 1659 | RSS: Main: 207167488 07:12:51 INFO - PROCESS | 1659 | 07:12:51 INFO - PROCESS | 1659 | __start_tp_report 07:12:51 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 07:12:51 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 07:12:51 INFO - PROCESS | 1659 | |i|pagename|runs| 07:12:51 INFO - PROCESS | 1659 | |0;ai-astar;107;124;120;119;120;120;119;120;121;143 07:12:51 INFO - PROCESS | 1659 | |1;audio-beat-detection;140;107;110;143;110;109;109;110;125;110 07:12:51 INFO - PROCESS | 1659 | |2;audio-dft;197;196;202;198;199;201;217;188;275;215 07:12:51 INFO - PROCESS | 1659 | |3;audio-fft;78;49;59;92;83;85;83;60;67;69 07:12:51 INFO - PROCESS | 1659 | |4;audio-oscillator;84;70;84;71;70;68;85;105;103;95 07:12:51 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;89;90;90;88;92;95;89;92;99;88 07:12:51 INFO - PROCESS | 1659 | |6;imaging-darkroom;96;97;97;97;96;106;97;96;101;154 07:12:51 INFO - PROCESS | 1659 | |7;imaging-desaturate;128;148;91;93;91;90;95;90;95;92 07:12:51 INFO - PROCESS | 1659 | |8;json-parse-financial;65;65;66;68;68;68;68;69;70;69 07:12:51 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;55;56;64;70;87;94;86;82;58;63 07:12:51 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;69;101;101;106;102;101;84;88;86;83 07:12:51 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;135;138;142;142;127;138;157;141;171;147 07:12:51 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;158;166;162;168;162;165;164;173;172;165 07:12:51 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;66;66;63;65;66;66;64;65;65;64 07:12:51 INFO - PROCESS | 1659 | __end_tp_report 07:12:51 INFO - PROCESS | 1659 | __start_cc_report 07:12:51 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,988 07:12:51 INFO - PROCESS | 1659 | __end_cc_report 07:12:51 INFO - PROCESS | 1659 | __startTimestamp1493043170999__endTimestamp 07:12:51 INFO - PROCESS | 1659 | 07:12:51 INFO - PROCESS | 1659 | ------- Summary: start ------- 07:12:51 INFO - PROCESS | 1659 | Number of tests: 14 07:12:51 INFO - PROCESS | 1659 | 07:12:51 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:121.30 Median:120.00 stddev:8.82 (7.3%) stddev-sans-first:7.69 07:12:51 INFO - PROCESS | 1659 | Values: 107.0 124.0 120.0 119.0 120.0 120.0 119.0 120.0 121.0 143.0 07:12:51 INFO - PROCESS | 1659 | 07:12:51 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:117.30 Median:110.00 stddev:13.71 (12.5%) stddev-sans-first:11.83 07:12:51 INFO - PROCESS | 1659 | Values: 140.0 107.0 110.0 143.0 110.0 109.0 109.0 110.0 125.0 110.0 07:12:51 INFO - PROCESS | 1659 | 07:12:51 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:208.80 Median:201.50 stddev:24.82 (12.3%) stddev-sans-first:25.95 07:12:51 INFO - PROCESS | 1659 | Values: 197.0 196.0 202.0 198.0 199.0 201.0 217.0 188.0 275.0 215.0 07:12:51 INFO - PROCESS | 1659 | 07:12:51 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:72.50 Median:80.50 stddev:13.83 (17.2%) stddev-sans-first:14.52 07:12:51 INFO - PROCESS | 1659 | Values: 78.0 49.0 59.0 92.0 83.0 85.0 83.0 60.0 67.0 69.0 07:12:51 INFO - PROCESS | 1659 | 07:12:51 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:83.50 Median:77.50 stddev:13.90 (17.9%) stddev-sans-first:14.74 07:12:51 INFO - PROCESS | 1659 | Values: 84.0 70.0 84.0 71.0 70.0 68.0 85.0 105.0 103.0 95.0 07:12:51 INFO - PROCESS | 1659 | 07:12:51 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:91.00 stddev:3.49 (3.8%) stddev-sans-first:3.61 07:12:51 INFO - PROCESS | 1659 | Values: 89.0 90.0 90.0 88.0 92.0 95.0 89.0 92.0 99.0 88.0 07:12:51 INFO - PROCESS | 1659 | 07:12:51 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:103.70 Median:96.50 stddev:17.95 (18.6%) stddev-sans-first:18.82 07:12:51 INFO - PROCESS | 1659 | Values: 96.0 97.0 97.0 97.0 96.0 106.0 97.0 96.0 101.0 154.0 07:12:51 INFO - PROCESS | 1659 | 07:12:51 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:101.30 Median:91.50 stddev:19.99 (21.8%) stddev-sans-first:18.72 07:12:51 INFO - PROCESS | 1659 | Values: 128.0 148.0 91.0 93.0 91.0 90.0 95.0 90.0 95.0 92.0 07:12:51 INFO - PROCESS | 1659 | 07:12:51 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:67.60 Median:68.00 stddev:1.71 (2.5%) stddev-sans-first:1.54 07:12:51 INFO - PROCESS | 1659 | Values: 65.0 65.0 66.0 68.0 68.0 68.0 68.0 69.0 70.0 69.0 07:12:51 INFO - PROCESS | 1659 | 07:12:51 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:76.00 stddev:14.50 (19.1%) stddev-sans-first:14.10 07:12:51 INFO - PROCESS | 1659 | Values: 55.0 56.0 64.0 70.0 87.0 94.0 86.0 82.0 58.0 63.0 07:12:51 INFO - PROCESS | 1659 | 07:12:51 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:92.10 Median:76.00 stddev:11.86 (15.6%) stddev-sans-first:9.17 07:12:51 INFO - PROCESS | 1659 | Values: 69.0 101.0 101.0 106.0 102.0 101.0 84.0 88.0 86.0 83.0 07:12:51 INFO - PROCESS | 1659 | 07:12:51 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:143.80 Median:142.00 stddev:12.32 (8.7%) stddev-sans-first:12.65 07:12:51 INFO - PROCESS | 1659 | Values: 135.0 138.0 142.0 142.0 127.0 138.0 157.0 141.0 171.0 147.0 07:12:51 INFO - PROCESS | 1659 | 07:12:51 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.50 Median:165.50 stddev:4.58 (2.8%) stddev-sans-first:3.97 07:12:51 INFO - PROCESS | 1659 | Values: 158.0 166.0 162.0 168.0 162.0 165.0 164.0 173.0 172.0 165.0 07:12:51 INFO - PROCESS | 1659 | 07:12:51 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.50 stddev:1.05 (1.6%) stddev-sans-first:1.05 07:12:51 INFO - PROCESS | 1659 | Values: 66.0 66.0 63.0 65.0 66.0 66.0 64.0 65.0 65.0 64.0 07:12:51 INFO - PROCESS | 1659 | -------- Summary: end -------- 07:12:51 INFO - PROCESS | 1659 | 07:12:51 INFO - TEST-INFO | 1659: exit 0 07:12:51 INFO - TEST-OK | kraken | took 59721ms 07:12:51 INFO - SUITE-END | took 1174s 07:12:52 INFO - Completed test suite (00:19:34) 07:12:52 INFO - javascript benchmark 07:12:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7992.544337953398, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3135.7770469516877, 3596.2807438512295, 3602.0994751312173, 3602.1534939289245, 3607.2605115951214, 4878.560929516343, 5228.607854410915, 5760.222369980052, 6115.082737589359, 6121.326654068049, 5166.914947860944, 6086.360932102132, 6097.634141951486, 6153.138560601508, 6174.19735434394, 5243.778758099728, 5477.780888764445, 5502.174673798942, 5521.171824226358, 5554.50009499145, 5492.148716948879, 5561.555075593943, 5578.525825304833, 5580.888382232353, 5608.551315894739, 5381.54256888163, 5426.321709786277, 5537.889242215153, 5614.438556144394, 5642.322921249456, 5480.314960629921, 5485.753141108659, 5499.422560631137, 5504.724763761836, 5505.476979686923, 5368.1679339702405, 5437.728113594344, 5442.102053161263, 5476.91784623231, 5479.123340265578, 6208.937910620881, 6229.127922090911, 6234.2830574483605, 6242.751449710031, 6279.215098112736, 6283.748650053993, 6396.456301214403, 6402.871942561169, 6415.101885736, 6433.356664333576, 6299.622022678654, 6300.495960323163, 6332.683365831736, 6516.185476815398, 6543.836404089889, 6192.287886892975, 6251.937480625181, 6256.7810126645345, 6273.843153921189, 6293.087502312231, 6719.664016799238, 6726.024726414643, 6738.966305168541, 6744.898826517606, 6747.122874026345, 5310.230016647642, 6382.106505089291, 6394.0, 6398.456131228804, 6399.712012959428, 6315.147455093595, 6351.587146835441, 6352.95176295906, 6386.744530218833, 6420.0690899819265, 6334.746610135543, 6432.485401167895, 6445.323241059621, 6447.1296374989715, 6448.548601597937, 5514.779408823602, 6311.305756366796, 6371.23545174582, 6406.135171751754, 6448.548601597843, 5039.319691841628, 5067.873303167376, 5139.460356662496, 5215.087359712035, 5220.112580861262, 4493.393391892118, 4563.224251877189, 4631.0, 4669.906601868011, 4690.95309046912, 6195.411435913581, 6251.499880009588, 6262.5616206866, 6276.560640755195, 6303.621782693053, 6506.804795856132, 6576.967115164393, 6588.604683718992, 6601.438877695354, 6644.235912869985, 6138.508919286446, 6146.494108943338, 6198.380161983766, 6225.28409232935, 6298.055291706189, 5747.5689323300085, 5748.770049197985, 5756.740946657411, 5778.046622307273, 5782.595218334676, 6756.290589488033, 6786.524943254023, 6802.863942721118, 6817.625030623363, 6855.520113592001, 6245.0632405139595, 6310.337414571404, 6436.0, 6578.342165783383, 6596.90104648421, 6724.4284235840505, 6745.662716864235, 6773.491988101011, 6784.3554862288, 6806.829829254309, 6382.32985536512, 6473.935260647521, 6482.837929051811, 6486.772962946275, 6496.155499784998, 5141.408737995179, 6154.3845615438095, 6272.8118156455375, 6316.115743795965, 6546.607203567801, 6260.0, 6294.622322660655, 6361.427471527585, 6374.426301632875, 6384.170057892445, 5231.372235331784, 6339.144215530844, 6355.713992870239, 6361.236651601837, 6366.267879193952, 6121.479674227562, 6202.72087756043, 6261.5616906816085, 6310.652914089769, 6318.336574659595, 6190.628562286277, 6260.593061450991, 6271.874562508816, 6275.937240627718, 6289.842753931189, 6290.716917738621, 6306.495480361652, 6309.148264984168, 6309.211348581427, 6318.304986451395], "value": 5960.612874450969, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [753.6307209467359, 824.5835852894287, 843.6414523827374, 935.5602866652671, 978.2320878110685, 40242.0, 41383.0, 42538.0, 42540.936476588104, 42733.78633106838, 40609.390859137064, 40792.796036019856, 40808.18383632325, 40854.79572602133, 40924.38613420801, 8289.129641387657, 8443.662253509852, 11335.943320283428, 11610.071194304477, 11864.406779660982, 11480.827787583152, 11520.02079823216, 11593.30440173588, 11598.884011159907, 11618.535258589647, 11557.711057223552, 11605.0, 11611.47748351322, 11618.767624647502, 11619.186656934018, 9609.663661771805, 11498.195126341201, 11555.826662600068, 11571.190016698834, 11593.072554195729, 11185.888141118567, 11228.49471773764, 11295.322280663186, 11319.547218111265, 11323.0, 34713.0, 34832.0, 34942.825285873405, 34992.65007349919, 35015.824920875486, 3930.508686414198, 3930.8231129599235, 3956.8417263309434, 3962.0689138052535, 4036.9394459083137, 26516.0, 26721.866390668118, 26833.0608428706, 28124.437511249864, 28248.71751282502, 3209.133533945845, 3262.13553408347, 3280.70473657372, 3310.5034244863223, 3417.333619944112, 322326.0, 326740.0, 366909.0, 369164.15417922736, 418248.999999997, 46325.07349852984, 46388.536114638424, 46462.30306545404, 46544.534554654696, 46566.53433465622, 40106.79946600307, 47734.28398574024, 48055.759721201866, 48357.0, 48402.03195936062, 13892.583222503341, 13967.930160349337, 14036.789448158286, 14113.364898579588, 14120.50578229757, 12427.565035223724, 12544.996400288137, 12551.874481255254, 12556.0, 12573.119881608383, 11672.774358692215, 12231.755364892651, 12251.75496490083, 12287.324197169062, 12329.198602090835, 4753.83361582343, 4756.405449318835, 4882.487338829372, 5695.515881150149, 5730.828075157752, 1131.8981291683624, 1189.2210602055668, 1189.52419032386, 1200.5137919142587, 1234.975300493985, 28227.15318540447, 28327.57508637372, 28688.0, 28753.56869646957, 28895.0, 1047.554789214596, 1071.4428497181423, 1107.5901916290863, 1117.463617463622, 1344.519361777519, 14786.334614942354, 15143.621409464631, 16872.987620742795, 17165.055921924413, 17358.305667773147, 486209.0, 489133.0, 489568.0, 490200.0, 490275.0, 61686.38313616896, 62006.0, 62432.37567624266, 62450.37549624446, 62489.37510624836, 1533.4173014254511, 1566.8981516201638, 1604.4224079331432, 1604.7271963765954, 1610.315615863277, 25333.0, 29573.852130739207, 31195.908143214878, 31202.687973120886, 31862.362752745277, 51971.0, 52077.7396113017, 52217.47782522126, 52230.47769522408, 52242.0, 472681.0, 473779.6311018423, 473821.63089184335, 473970.0, 474404.0, 34034.0, 34544.96365109051, 36362.090947726516, 37483.0, 37513.43729844109, 41132.79433602813, 41527.377089344285, 42199.367009494264, 42599.93500162397, 42776.14447711091, 28832.98169128141, 29492.705072948997, 30233.69766302309, 30290.69709302967, 30299.848500757354, 28585.0, 29153.41693166167, 30064.849675752357, 30071.0, 30092.54861177128, 35080.298394032485, 35126.64873351234, 35221.471677925365, 35493.0, 35541.64458355383, 22698.659520107543, 29428.969986050386, 29676.55485167681, 29697.406051879272, 30337.393252135276, 23627.40931476727, 23966.40083997914, 24043.51912961766, 24136.75863241345, 24309.878450608343], "value": 19322.434212073975, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.80153728516234, 437.9255526560485, 439.6765735137238, 447.99328010079836, 450.9852831129958, 20433.386998390055, 21502.89248553757, 22453.775462245372, 22674.54650906985, 22797.886010569946, 23489.0, 23507.412314692097, 23928.04287828489, 24149.75850241497, 24272.0, 6877.965610171942, 6969.756058537965, 7129.429645628337, 7248.927510724878, 7251.818704532402, 6980.9650951745425, 7080.53976491529, 7102.573845569256, 7114.64426778659, 7131.536450130725, 6753.493487988396, 7052.576845389267, 7059.117610298713, 7115.146182458112, 7120.715171393138, 6878.209005964303, 6910.861782764317, 7069.858602827941, 7085.787426377217, 7153.141623005246, 6874.8625027499675, 6913.5506192097855, 6917.169939607229, 6919.238883722786, 6920.377166055079, 28240.576391354145, 28460.861565537354, 28550.428991420056, 28806.279843004093, 28910.710892891013, 3069.41681080594, 3091.180837078176, 3109.782315237934, 3462.394081035834, 3613.223157021253, 16555.83444165555, 17515.547823304747, 17615.207315670825, 18175.363862264756, 18224.179911903997, 1911.4934542346289, 2066.9483262918548, 2091.8326533877253, 2104.779228047729, 2192.7916847899423, 133516.0, 135860.32069839587, 143389.0, 144274.0, 145441.8183727245, 18194.818051819577, 21868.23461178883, 23021.309360719206, 23036.0, 23370.64944025841, 31095.68904310973, 37690.811545942095, 38490.80754596209, 38977.80511097427, 38987.6101238984, 10533.7886143093, 10720.142388608892, 11089.778204435866, 11302.37836918961, 11341.886581134084, 7234.493585449073, 7452.919326697598, 8040.919590804017, 8924.196822285896, 9079.68221112258, 7876.960615197002, 9169.908300917039, 10846.94576527128, 11315.94342028301, 11396.202265841312, 4194.538600753915, 4557.384753058361, 4702.719181197117, 4715.269133284346, 4762.52374762521, 945.4752612300102, 1017.85999680357, 1072.892710728921, 1125.139268459629, 1126.7352172239516, 20630.690539641917, 23198.65202021971, 23313.533729325318, 23469.41326466818, 23474.943627536803, 1004.8894621591619, 1031.3090229546071, 1103.9889601104046, 1121.1367247219596, 1143.9256448330998, 9676.951615241878, 12111.757764844653, 12143.635690929286, 12371.0, 12383.876161238271, 719529.0, 729896.0, 751934.0, 787129.0, 793521.0, 136281.9557706635, 162343.0, 172767.0, 172897.13551432162, 180163.0, 1121.7924683933497, 1357.8234829472106, 1394.525861207174, 1395.8534353892899, 1396.4204854985067, 20871.269505567234, 20957.89521052385, 21231.815227715968, 21473.0, 21488.92555372194, 54648.0, 60360.69819650874, 66831.33168668445, 66976.33023669702, 67279.66360168168, 93075.06924930665, 93589.5320523393, 93789.5310523443, 93807.53096234475, 95774.52112739392, 11763.353015584225, 11845.289282643069, 11903.940480297542, 12001.699532628249, 12069.57756478519, 11758.529658813552, 12897.226166430044, 13040.478380864659, 13066.281354525592, 13325.067245293125, 10862.34825910448, 11187.049100826522, 11254.88745112571, 11314.377709226072, 11511.884881151082, 10527.631532896312, 10616.0, 10681.252312338062, 10802.405867677353, 10858.837117443087, 11605.825912611148, 11752.23610465317, 11772.352520611448, 11877.643670689893, 11962.162648614514, 11810.940945295219, 11816.231944923553, 11949.462274197856, 12019.03847692165, 12114.394280285775, 11264.380459074502, 11331.773364532828, 11507.0, 11606.767864642828, 11635.650930472098], "value": 12183.269398392764, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1629.3808352825924, 1661.5596866830292, 1663.9251233694483, 1666.3751093340004, 1692.2723229011524, 11378.943105284472, 11598.536058557647, 12004.979576735966, 12175.756484870308, 12204.877951220486, 11376.488058037388, 11565.942170289136, 11569.305841649508, 11575.53697852085, 11594.362310072942, 2455.4352498925214, 2489.7759201671847, 2503.7245902950663, 2505.5364757519824, 2512.660790793238, 2467.506498700267, 2487.6268559716004, 2505.1732928133674, 2516.1822407717473, 2522.6846644169477, 2480.293116461809, 2491.289982355029, 2500.6749122614124, 2525.1666949906485, 2525.229804909502, 2077.241806740544, 2458.4222707663685, 2481.962770558443, 2484.714257860342, 2500.94998100037, 2460.4709987352803, 2479.2686157583557, 2480.677511923456, 2490.7384724604017, 2494.625806129077, 11151.107911367153, 11251.043661288719, 11290.661280161608, 11329.546818127264, 11340.319580825095, 5994.910076348859, 6158.84602884927, 6167.814965551041, 6169.2288463942, 6241.251049873998, 3667.669909708139, 3770.5286839145106, 3785.0726571989762, 3818.1409182933894, 3879.398693202558, 1276.6935935375536, 1918.5587314917507, 1968.468513501361, 1988.3040935672543, 2036.7250421193098, 14234.92882535591, 14528.927355363261, 15430.228488575638, 16293.266802993894, 16445.177741112897, 4655.720656760537, 4886.413630364379, 4997.425296090923, 5175.301334319894, 5231.346081739783, 13579.86420135786, 13603.115797473132, 13642.658933526742, 13659.863401366058, 13695.452181912611, 6212.47193988507, 6213.440790328802, 6275.1842260506755, 6275.215598050244, 6279.497640188774, 5560.499555039985, 5643.35101463337, 5653.604247702621, 5673.092305231144, 5741.741621626953, 4300.376445415397, 4323.308270676676, 4396.978015109968, 4408.118376324684, 4444.466664000341, 1182.2256422043574, 1210.425048102162, 1224.3510939202238, 1224.4428784902848, 1229.5819421396588, 863.7797361672733, 945.4374647084974, 952.9761755956157, 968.8159249742527, 1007.3603261928581, 6815.352541508549, 6995.825104372431, 7219.205887352492, 7228.746993855295, 7240.348368646847, 1009.7728011197422, 1049.8399268807857, 1088.6026600290995, 1099.1042300525046, 1140.3670962615663, 4315.741055536678, 4438.400815889878, 4483.923858274056, 4514.390557274792, 4636.0, 10675.89324106749, 11731.648050558497, 13708.794368084487, 14298.142111473346, 14326.426942922375, 12121.333326666942, 13890.58328250154, 14406.783898241534, 14414.495492657707, 14424.495142670166, 8928.99999999987, 10239.948800255952, 10477.738056548495, 10486.0, 10513.684589462327, 6644.568103073285, 7029.578225306498, 7189.496735228484, 7209.423246140192, 7294.63526823646, 11919.225250358699, 12029.15795894279, 12246.326452044867, 12389.318587477415, 12393.938030309791, 12544.372781361079, 12586.937065314614, 12671.429785659851, 12761.042921781089, 12957.028222883133, 9459.952700236454, 9582.377145485521, 9717.076877696607, 9743.415395076318, 9747.756306092404, 10416.427096509764, 10507.737306567396, 10574.947125264325, 10646.946765266433, 10664.626738064131, 8913.420627659181, 8980.191782739585, 9022.774430639287, 9024.458532488072, 9201.76995575116, 8827.602757876039, 8873.423227490193, 8876.423032502867, 8962.283017358464, 8972.41679290844, 8064.959675201587, 8219.383546233938, 8237.588120593826, 8246.38152138604, 8250.876236856571, 6081.908771368521, 6085.604435711664, 6118.847028824315, 6231.065340198825, 6287.30839607652, 6078.756849725962, 6165.630062196283, 6232.314445410911, 6284.151639528605, 6306.527010474325], "value": 5454.880825248244, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.48794299325374, 401.4660501532961, 402.8799936177427, 405.7666841566098, 407.57815660791067, 4333.068390296085, 4500.324951257313, 4710.563278200156, 5169.224616307556, 5218.347706536683, 5051.345827074153, 5180.430152683201, 5208.427073021964, 5216.556592689626, 5305.734713264341, 3647.598764135943, 4144.626983571478, 4155.210510003105, 4163.396307535406, 4169.854055108071, 3501.9299614007705, 3865.1833638190005, 3942.9354074399894, 4169.3954376615375, 4242.809073591696, 3538.716902647795, 3570.0539357070397, 3665.047087757189, 4196.412502249687, 4226.175895700339, 3674.0, 3825.9234815303817, 3921.117748506592, 3948.091938854065, 4171.478565179353, 3007.7293043626178, 3543.326767914114, 3723.4733759158885, 3724.3426675129576, 3749.7562658427114, 4322.789618906711, 4449.26587113126, 4852.684575502581, 5180.818671346522, 5185.714785686786, 3875.283072631544, 3971.6624086952643, 4782.258749893772, 4839.927401088987, 4883.291922671193, 5604.0, 5618.606697531174, 5641.605087643867, 5655.095184770417, 5710.685912274823, 3552.6980206682465, 3773.6980142375423, 4156.521999970012, 4157.355609880473, 4177.582241775828, 5204.791808327663, 5355.3305836770405, 5732.22614946981, 5774.8161626866395, 5967.030879809652, 3475.4960530722906, 3875.6318149775725, 3993.4625169309443, 4202.931859742405, 4223.303154979455, 3755.493008443825, 4219.852305169305, 4270.7864606769535, 4273.957260427418, 4275.679324050709, 4257.978085259578, 4291.03451723366, 4400.647948164139, 4858.562729354375, 4876.536729010738, 4980.950190498121, 5578.665280083208, 5628.04323265046, 5656.37779844225, 5693.4875861172695, 1734.6530693861278, 1748.8950662960262, 1758.4613463219682, 1773.6630040292303, 1797.173300281859, 1225.5526732742662, 1240.1752834365227, 1310.7575098606787, 1360.2246719369864, 1427.5431861804122, 4743.075100355398, 5100.030994111181, 5177.559907407913, 5287.048331300402, 5457.454254574511, 5856.590038697335, 5890.941090589039, 5895.199780104416, 5900.734466948998, 5929.495992840657, 4717.5940270462315, 4964.702117872867, 5098.1588037973315, 5135.6148288878485, 5493.406911995557, 2754.9724502755116, 2919.8320671731476, 3027.197792584923, 3036.375539086578, 3054.740347070524, 4870.537298956593, 5448.9727551362785, 5617.960692255841, 5870.5637160578735, 6037.6679282639, 5432.158015507603, 5524.889502209933, 5592.790847615251, 5968.880622387528, 6039.63762174271, 5226.634135610547, 5501.477359650827, 5626.718664066862, 5702.429757024347, 5953.702314884238, 5718.885622287614, 5814.651120932757, 5904.645721256738, 5904.844731672994, 5907.881842363215, 4868.634852386156, 5149.382074151126, 5642.463965923231, 5688.0, 5757.913631295618, 5825.592208545361, 5894.734736936763, 6083.908741368971, 6133.877322453615, 6197.628142311476, 4250.404943307877, 4367.061081867414, 4681.274402467623, 4689.827543114221, 4706.270528068155, 4719.551642593948, 4751.524847515221, 5404.081306178038, 5795.406263277632, 5825.912611310919, 5221.895562088813, 5607.859803504945, 5637.46444087811, 5694.629849059798, 5719.227904233042, 4672.57946784791, 4882.804687812448, 5249.6062795289745, 5624.521915637217, 5677.630953987978, 5081.949180508148, 5490.121580547093, 5528.834134975958, 5570.693611851387, 5578.748956297058, 5120.48795120485, 5395.433479484597, 5707.714614269353, 5729.931748458641, 5732.570057245806, 4176.895577610584, 4809.44691360498, 4818.951945423251, 4902.656814022984, 5062.949505450299], "value": 4226.291132286372, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [754.1139161485254, 762.26441483968, 794.4359504751626, 894.3381897395927, 902.3638334973842, 16490.587735306624, 17163.313467461285, 17379.565510862234, 17442.215100320485, 18084.0, 15023.248837558105, 16385.754213686774, 16443.753343699824, 16757.581060473494, 16782.83217167828, 971.2569884038699, 987.1017374189489, 991.3011327014474, 993.7776534862124, 994.9651762188325, 944.6552008516876, 976.3214565876718, 1015.9695209143738, 1020.8928062553437, 1020.9642662506814, 1077.030672394847, 1085.0, 1091.3506463654153, 1091.956321747129, 1093.316677076827, 1071.6356438810842, 1082.1505118482032, 1083.2796190533286, 1087.2932593814005, 1089.4008295437518, 792.4928046050533, 795.2762985683, 799.3645052183529, 800.0599295827379, 801.2148094867063, 17043.57391065221, 17073.914630426767, 17195.39816106443, 17916.55208619782, 18188.818111818844, 14756.557303280919, 14783.408663653441, 14859.257037148207, 14859.70280594382, 15026.17356045428, 11827.467763950557, 12169.513219471211, 12236.143469957107, 12286.569970051005, 12321.815172772416, 3781.489498917666, 3848.5574158971517, 3851.480050193216, 3904.3557812960826, 4036.1725846201753, 115006.0, 121064.39467802604, 121681.39159304235, 122319.0, 122358.0, 11196.38419886914, 11326.943365283285, 11424.657260282205, 11610.24533405326, 11981.64055078349, 23907.760922390902, 24179.51640967206, 24199.274021779376, 24430.389240269134, 24503.50992980166, 7998.960135182389, 8045.0, 8110.0, 8214.876776848352, 8378.79053023667, 3938.3501722215515, 4082.1631565528996, 4419.867403977886, 4557.0, 4575.95424045762, 3635.327464419091, 3701.7038636908983, 3751.1934933989323, 3844.154286057063, 4029.1135950090934, 638.1257676982564, 719.5287086958051, 723.1177962885272, 725.9092613423322, 737.2958824322759, 632.867097909435, 712.2023333866103, 720.7873677265219, 749.9100107986967, 766.9689407229912, 10362.533685984148, 10429.165666746641, 10485.737856553647, 10730.517126729315, 10773.569057237622, 689.348565605498, 708.6208878250214, 722.3282347416953, 747.1482509938675, 749.82753966588, 5246.895062098813, 5271.446498117719, 5386.0, 5389.811356602579, 5394.32570928634, 106322.93677063285, 106831.0, 108757.0, 111813.0, 112138.0, 73265.0, 89779.0, 93453.0, 93538.06461935429, 94982.0, 52923.0, 84549.15450845413, 86226.0, 86366.0, 86512.13487865166, 9763.902360976299, 9992.750181245527, 10221.744456388353, 10322.845157322496, 10325.432101234504, 49560.0, 53662.46337536731, 53697.0, 54975.450245498636, 55558.0, 69051.0, 70080.0, 70154.0, 72045.0, 72247.0, 11501.82747258775, 11578.536858525564, 12341.938290308492, 12710.491580336682, 12748.235105893677, 15112.0, 15700.607484812972, 15917.761233581274, 15921.124338161513, 16106.114163721108, 10228.181745460197, 12004.459799308877, 14079.718405632035, 14756.926215369283, 15179.924100379427, 11247.66257012291, 14238.471706112841, 14426.350814213596, 14608.78086828677, 14790.0, 14262.286885655883, 14393.424263029361, 14485.275736212936, 15027.849721502645, 15284.159371234244, 9811.354412879598, 10153.340032897839, 10247.590096396061, 10522.789544209227, 10548.0, 9794.853077203705, 10191.388516689023, 10727.356358618508, 11790.528378864748, 12059.57791477294], "value": 8057.639320446226, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1505.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 124.0, 120.0, 119.0, 120.0, 120.0, 119.0, 120.0, 121.0, 143.0], "value": 121.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 107.0, 110.0, 143.0, 110.0, 109.0, 109.0, 110.0, 125.0, 110.0], "value": 117.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 196.0, 202.0, 198.0, 199.0, 201.0, 217.0, 188.0, 275.0, 215.0], "value": 208.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 49.0, 59.0, 92.0, 83.0, 85.0, 83.0, 60.0, 67.0, 69.0], "value": 72.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 84.0, 71.0, 70.0, 68.0, 85.0, 105.0, 103.0, 95.0], "value": 83.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 90.0, 88.0, 92.0, 95.0, 89.0, 92.0, 99.0, 88.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 97.0, 96.0, 106.0, 97.0, 96.0, 101.0, 154.0], "value": 103.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 148.0, 91.0, 93.0, 91.0, 90.0, 95.0, 90.0, 95.0, 92.0], "value": 101.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 68.0, 68.0, 68.0, 68.0, 69.0, 70.0, 69.0], "value": 67.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 64.0, 70.0, 87.0, 94.0, 86.0, 82.0, 58.0, 63.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 101.0, 101.0, 106.0, 102.0, 101.0, 84.0, 88.0, 86.0, 83.0], "value": 92.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 138.0, 142.0, 142.0, 127.0, 138.0, 157.0, 141.0, 171.0, 147.0], "value": 143.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 166.0, 162.0, 168.0, 162.0, 165.0, 164.0, 173.0, 172.0, 165.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 63.0, 65.0, 66.0, 66.0, 64.0, 65.0, 65.0, 64.0], "value": 65.0, "unit": "score"}]}]} 07:12:52 INFO - Return code: 0 07:12:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:12:52 ERROR - # TBPL SUCCESS # 07:12:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:12:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:12:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:12:52 INFO - 2017-04-24 07:12:52.357 screenresolution[1664:10703] starting screenresolution argv=screenresolution get 07:12:52 INFO - 2017-04-24 07:12:52.360 screenresolution[1664:10703] Display 0: 1600x1200x32@60 07:12:52 INFO - 2017-04-24 07:12:52.366 screenresolution[1665:10706] starting screenresolution argv=screenresolution list 07:12:52 INFO - Available Modes on Display 0 07:12:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:12:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:12:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:12:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:12:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:12:52 INFO - Intel Iris: 07:12:52 INFO - Chipset Model: Intel Iris 07:12:52 INFO - Type: GPU 07:12:52 INFO - Bus: Built-In 07:12:52 INFO - VRAM (Dynamic, Max): 1536 MB 07:12:52 INFO - Vendor: Intel (0x8086) 07:12:52 INFO - Device ID: 0x0a2e 07:12:52 INFO - Revision ID: 0x0009 07:12:52 INFO - Displays: 07:12:52 INFO - Display: 07:12:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:12:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:12:52 INFO - Display Serial Number: 1600x1200 60 07:12:52 INFO - Main Display: Yes 07:12:52 INFO - Mirror: Off 07:12:52 INFO - Online: Yes 07:12:52 INFO - Rotation: Supported 07:12:52 INFO - Return code: 0 07:12:52 INFO - Running post-action listener: _resource_record_post_action 07:12:52 INFO - [mozharness: 2017-04-24 14:12:52.601592Z] Finished run-tests step (success) 07:12:52 INFO - Running post-run listener: _resource_record_post_run 07:12:52 INFO - Total resource usage - Wall time: 1190s; CPU: 54.0%; Read bytes: 34369536; Write bytes: 428634624; Read time: 211; Write time: 1458 07:12:52 INFO - TinderboxPrint: CPU usage
54.4% 07:12:52 INFO - TinderboxPrint: I/O read bytes / time
34,369,536 / 211 07:12:52 INFO - TinderboxPrint: I/O write bytes / time
428,634,624 / 1,458 07:12:52 INFO - TinderboxPrint: CPU idle
2,171.6 (45.6%) 07:12:52 INFO - TinderboxPrint: CPU system
952.3 (20.0%) 07:12:52 INFO - TinderboxPrint: CPU user
1,637.7 (34.4%) 07:12:52 INFO - TinderboxPrint: Swap in / out
152,797,184 / 0 07:12:52 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 122970624; Write bytes: 122630144; Read time: 11168; Write time: 278 07:12:52 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 30347264; Write bytes: 304493056; Read time: 184; Write time: 1175 07:12:53 INFO - Running post-run listener: _upload_blobber_files 07:12:53 INFO - Blob upload gear active. 07:12:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:12:53 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 07:12:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:12:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:12:53 INFO - (blobuploader) - INFO - Open directory for files ... 07:12:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:12:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:12:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:12:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:12:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:12:54 INFO - (blobuploader) - INFO - Done attempting. 07:12:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:12:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:12:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:12:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:12:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:12:55 INFO - (blobuploader) - INFO - Done attempting. 07:12:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:12:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:12:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:12:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:12:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:12:56 INFO - (blobuploader) - INFO - Done attempting. 07:12:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:12:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:12:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:12:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:12:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:12:58 INFO - (blobuploader) - INFO - Done attempting. 07:12:58 INFO - (blobuploader) - INFO - Iteration through files over. 07:12:58 INFO - Return code: 0 07:12:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:12:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:12:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3111beba87a1d9998e37e01bfc6f6ce63ce0d149d06ecca08127a373f998d93266ca1aeb5f9fd8a72f963f9e7ead59f83082312f550708a55a30fd7726d58802", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f04c99ad492691dcd82bafa35685c094c72ec7450ff5ebbf1bfa9ec08dfac1e48124ffa0cc0aad62b2ab4724dd5607bca341b877d7df492678b6b0b2f5efb7f5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1bf5985c232b844d7dfd5a849b76e076b14f10292b10a1157fbb8e5a52fd1bbbbc1e05cba7e8ad68b3108d557b4c9c50f5c485326d1be87650c0d3871efbb987", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fab660d0e99d4053e4ea82e64358b8ef10c768c1da3e84d70667a0d18820f7232a303e8da219def96a0ef79ba3968554908a2647842f10c262285bba8e20933d"} 07:12:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:12:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:12:58 INFO - Contents: 07:12:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3111beba87a1d9998e37e01bfc6f6ce63ce0d149d06ecca08127a373f998d93266ca1aeb5f9fd8a72f963f9e7ead59f83082312f550708a55a30fd7726d58802", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f04c99ad492691dcd82bafa35685c094c72ec7450ff5ebbf1bfa9ec08dfac1e48124ffa0cc0aad62b2ab4724dd5607bca341b877d7df492678b6b0b2f5efb7f5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1bf5985c232b844d7dfd5a849b76e076b14f10292b10a1157fbb8e5a52fd1bbbbc1e05cba7e8ad68b3108d557b4c9c50f5c485326d1be87650c0d3871efbb987", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fab660d0e99d4053e4ea82e64358b8ef10c768c1da3e84d70667a0d18820f7232a303e8da219def96a0ef79ba3968554908a2647842f10c262285bba8e20933d"} 07:12:58 INFO - Running post-run listener: copy_logs_to_upload_dir 07:12:58 INFO - Copying logs to upload dir... 07:12:58 INFO - mkdir: /builds/slave/test/build/upload/logs 07:12:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.852157 ========= master_lag: -0.50 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-04-24 07:12:57.949180) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-24 07:12:57.953203) ========= 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.7rgF4xVhV6/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.KZ4IsaeT2M/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3111beba87a1d9998e37e01bfc6f6ce63ce0d149d06ecca08127a373f998d93266ca1aeb5f9fd8a72f963f9e7ead59f83082312f550708a55a30fd7726d58802", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f04c99ad492691dcd82bafa35685c094c72ec7450ff5ebbf1bfa9ec08dfac1e48124ffa0cc0aad62b2ab4724dd5607bca341b877d7df492678b6b0b2f5efb7f5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1bf5985c232b844d7dfd5a849b76e076b14f10292b10a1157fbb8e5a52fd1bbbbc1e05cba7e8ad68b3108d557b4c9c50f5c485326d1be87650c0d3871efbb987", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fab660d0e99d4053e4ea82e64358b8ef10c768c1da3e84d70667a0d18820f7232a303e8da219def96a0ef79ba3968554908a2647842f10c262285bba8e20933d"} build_url:https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011146 build_url: 'https://queue.taskcluster.net/v1/task/CUxrGYd9Tw2oS9xKS8LCVg/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3111beba87a1d9998e37e01bfc6f6ce63ce0d149d06ecca08127a373f998d93266ca1aeb5f9fd8a72f963f9e7ead59f83082312f550708a55a30fd7726d58802", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f04c99ad492691dcd82bafa35685c094c72ec7450ff5ebbf1bfa9ec08dfac1e48124ffa0cc0aad62b2ab4724dd5607bca341b877d7df492678b6b0b2f5efb7f5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1bf5985c232b844d7dfd5a849b76e076b14f10292b10a1157fbb8e5a52fd1bbbbc1e05cba7e8ad68b3108d557b4c9c50f5c485326d1be87650c0d3871efbb987", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fab660d0e99d4053e4ea82e64358b8ef10c768c1da3e84d70667a0d18820f7232a303e8da219def96a0ef79ba3968554908a2647842f10c262285bba8e20933d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-24 07:12:57.992275) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 07:12:57.992705) ========= 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.7rgF4xVhV6/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.KZ4IsaeT2M/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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.004569 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 07:12:58.040744) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-24 07:12:58.041123) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-24 07:12:58.041422) ========= ========= Total master_lag: -0.35 =========