builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0070 starttime: 1489727952.47 results: success (0) buildid: 20170316212021 builduid: fd0ca423b45c44828d300212468b05b8 revision: 20f404abbe681ecb3203095c7583354d54302adf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-16 22:19:12.470580) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-16 22:19:12.471061) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-16 22:19:12.471357) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-16 22:19:12.501560) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 22:19:12.501998) ========= 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.OhrXOb8r1c/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.7rEbpN27Jw/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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-03-16 22:19:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.0M=0.001s 2017-03-16 22:19:12 (14.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117802 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 22:19:12.644271) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 22:19:12.644606) ========= 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.OhrXOb8r1c/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.7rEbpN27Jw/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.027450 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 22:19:12.697208) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-16 22:19:12.697592) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 20f404abbe681ecb3203095c7583354d54302adf --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 20f404abbe681ecb3203095c7583354d54302adf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OhrXOb8r1c/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.7rEbpN27Jw/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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-03-16 22:19:12,948 truncating revision to first 12 chars 2017-03-16 22:19:12,948 Setting DEBUG logging. 2017-03-16 22:19:12,948 attempt 1/10 2017-03-16 22:19:12,948 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/20f404abbe68?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-16 22:19:14,611 unpacking tar archive at: mozilla-beta-20f404abbe68/testing/mozharness/ program finished with exit code 0 elapsedTime=1.937495 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-16 22:19:14.666153) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-16 22:19:14.666568) ========= script_repo_revision: 20f404abbe681ecb3203095c7583354d54302adf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-16 22:19:14.667034) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-16 22:19:14.667353) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-16 22:19:14.689585) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-16 22:19:14.690047) ========= /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.OhrXOb8r1c/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.7rEbpN27Jw/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 22:19:15 INFO - MultiFileLogger online at 20170316 22:19:15 in /builds/slave/test 22:19:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 22:19:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:19:15 INFO - {'append_to_log': False, 22:19:15 INFO - 'base_work_dir': '/builds/slave/test', 22:19:15 INFO - 'blob_upload_branch': 'Mozilla-Beta', 22:19:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:19:15 INFO - 'branch': 'Mozilla-Beta', 22:19:15 INFO - 'buildbot_json_path': 'buildprops.json', 22:19:15 INFO - 'config_files': ('talos/mac_config.py',), 22:19:15 INFO - 'default_actions': ('clobber', 22:19:15 INFO - 'read-buildbot-config', 22:19:15 INFO - 'download-and-extract', 22:19:15 INFO - 'populate-webroot', 22:19:15 INFO - 'create-virtualenv', 22:19:15 INFO - 'install', 22:19:15 INFO - 'run-tests'), 22:19:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:19:15 INFO - 'download_minidump_stackwalk': True, 22:19:15 INFO - 'download_symbols': 'ondemand', 22:19:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:19:15 INFO - 'tooltool.py': '/tools/tooltool.py', 22:19:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:19:15 INFO - '/tools/misc-python/virtualenv.py')}, 22:19:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:19:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:19:15 INFO - 'installer_path': 'installer.exe', 22:19:15 INFO - 'log_level': 'info', 22:19:15 INFO - 'log_name': 'talos', 22:19:15 INFO - 'log_to_console': True, 22:19:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:19:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:19:15 INFO - 'opt_config_files': (), 22:19:15 INFO - 'pip_index': False, 22:19:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:19:15 INFO - 'cmd': ('bash', 22:19:15 INFO - '-c', 22:19:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:19:15 INFO - 'enabled': True, 22:19:15 INFO - 'halt_on_failure': False, 22:19:15 INFO - 'name': 'check_screen_resolution'},), 22:19:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:19:15 INFO - 'cmd': ('bash', 22:19:15 INFO - '-c', 22:19:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:19:15 INFO - 'enabled': True, 22:19:15 INFO - 'halt_on_failure': False, 22:19:15 INFO - 'name': 'check_screen_resolution'},), 22:19:15 INFO - 'run_cmd_checks_enabled': True, 22:19:15 INFO - 'sps_profile': False, 22:19:15 INFO - 'sps_profile_interval': 0, 22:19:15 INFO - 'suite': 'dromaeojs-e10s', 22:19:15 INFO - 'system_bits': '32', 22:19:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:19:15 INFO - 'title': 't-yosemite-r7-0070', 22:19:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:19:15 INFO - 'use_talos_json': True, 22:19:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:19:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:19:15 INFO - 'work_dir': 'build'} 22:19:15 INFO - [mozharness: 2017-03-17 05:19:15.058303Z] Running clobber step. 22:19:15 INFO - Running pre-action listener: _resource_record_pre_action 22:19:15 INFO - Running main action method: clobber 22:19:15 INFO - rmtree: /builds/slave/test/build 22:19:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:19:17 INFO - Running post-action listener: _resource_record_post_action 22:19:17 INFO - [mozharness: 2017-03-17 05:19:17.508354Z] Finished clobber step (success) 22:19:17 INFO - [mozharness: 2017-03-17 05:19:17.508480Z] Running read-buildbot-config step. 22:19:17 INFO - Running pre-action listener: _resource_record_pre_action 22:19:17 INFO - Running main action method: read_buildbot_config 22:19:17 INFO - Using buildbot properties: 22:19:17 INFO - { 22:19:17 INFO - "project": "", 22:19:17 INFO - "product": "firefox", 22:19:17 INFO - "script_repo_revision": "production", 22:19:17 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 22:19:17 INFO - "repository": "", 22:19:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 22:19:17 INFO - "buildid": "20170316212021", 22:19:17 INFO - "pgo_build": "False", 22:19:17 INFO - "basedir": "/builds/slave/test", 22:19:17 INFO - "buildnumber": 1, 22:19:17 INFO - "slavename": "t-yosemite-r7-0070", 22:19:17 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 22:19:17 INFO - "platform": "yosemite_r7", 22:19:17 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 22:19:17 INFO - "branch": "mozilla-beta", 22:19:17 INFO - "repo_path": "releases/mozilla-beta", 22:19:17 INFO - "revision": "20f404abbe681ecb3203095c7583354d54302adf", 22:19:17 INFO - "stage_platform": "macosx64", 22:19:17 INFO - "builduid": "fd0ca423b45c44828d300212468b05b8", 22:19:17 INFO - "slavebuilddir": "test" 22:19:17 INFO - } 22:19:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 22:19:17 INFO - Running post-action listener: _resource_record_post_action 22:19:17 INFO - [mozharness: 2017-03-17 05:19:17.511857Z] Finished read-buildbot-config step (success) 22:19:17 INFO - [mozharness: 2017-03-17 05:19:17.511976Z] Running download-and-extract step. 22:19:17 INFO - Running pre-action listener: _resource_record_pre_action 22:19:17 INFO - Running main action method: download_and_extract 22:19:17 INFO - mkdir: /builds/slave/test/build/tests 22:19: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')]} 22:19:17 INFO - trying https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 22:19:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 22:19:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 22:19:18 INFO - Downloaded 1165 bytes. 22:19:18 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 22:19:18 INFO - Using the following test package requirements: 22:19:18 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 22:19:18 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 22:19:18 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 22:19:18 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 22:19:18 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 22:19:18 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 22:19:18 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 22:19:18 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 22:19:18 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 22:19:18 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 22:19:18 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 22:19:18 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 22:19:18 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 22:19:18 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 22:19:18 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 22:19:18 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 22:19:18 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 22:19:18 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 22:19:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 22:19:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 22:19:18 INFO - Fetch https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 22:19:19 INFO - Expected file size: 16106566 22:19:19 INFO - Obtained file size: 16106566 22:19:26 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 22:19:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 22:19:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 22:19:26 INFO - Fetch https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 22:19:27 INFO - Expected file size: 16106566 22:19:27 INFO - Obtained file size: 16106566 22:19:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 22:19:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 22:19:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 22:19:35 INFO - Expected file size: 14403650 22:19:35 INFO - Obtained file size: 14403650 22:19:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:19:35 INFO - trying https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 22:19:35 INFO - Downloading https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 22:19:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 22:19:37 INFO - Downloaded 58700648 bytes. 22:19:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 22:19:37 INFO - mkdir: /builds/slave/test/properties 22:19:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:19:37 INFO - Writing to file /builds/slave/test/properties/build_url 22:19:37 INFO - Contents: 22:19:37 INFO - build_url:https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 22:19:37 INFO - Running post-action listener: _resource_record_post_action 22:19:37 INFO - Running post-action listener: set_extra_try_arguments 22:19:37 INFO - [mozharness: 2017-03-17 05:19:37.847828Z] Finished download-and-extract step (success) 22:19:37 INFO - [mozharness: 2017-03-17 05:19:37.848001Z] Running populate-webroot step. 22:19:37 INFO - Running pre-action listener: _resource_record_pre_action 22:19:37 INFO - Running main action method: populate_webroot 22:19:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:19:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:19:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:19:37 INFO - '--tppagecycles', 22:19:37 INFO - '7'], 22:19:37 INFO - 'tests': ['tsvgm']}}, 22:19:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:19:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:19:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:19:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:19:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:19:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:19:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:19:37 INFO - 'talos_options': ['--disable-e10s'], 22:19:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:19:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:19:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:19:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:19:37 INFO - 'talos_options': ['--disable-e10s'], 22:19:37 INFO - 'tests': ['damp', 'tps']}, 22:19:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:19:37 INFO - 'tests': ['damp', 'tps']}, 22:19:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:19:37 INFO - 'tests': ['dromaeo_dom']}, 22:19:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:19:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:19:37 INFO - 'tests': ['basic_compositor_video']}, 22:19:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:19:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:19:37 INFO - 'tests': ['a11yr', 22:19:37 INFO - 'ts_paint', 22:19:37 INFO - 'tpaint', 22:19:37 INFO - 'sessionrestore', 22:19:37 INFO - 'sessionrestore_no_auto_restore', 22:19:37 INFO - 'tabpaint']}, 22:19:37 INFO - 'other-e10s': {'tests': ['a11yr', 22:19:37 INFO - 'ts_paint', 22:19:37 INFO - 'tpaint', 22:19:37 INFO - 'sessionrestore', 22:19:37 INFO - 'sessionrestore_no_auto_restore', 22:19:37 INFO - 'tabpaint']}, 22:19:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:19:37 INFO - 'ts_paint', 22:19:37 INFO - 'tpaint', 22:19:37 INFO - 'sessionrestore', 22:19:37 INFO - 'sessionrestore_no_auto_restore', 22:19:37 INFO - 'tabpaint']}, 22:19:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:19:37 INFO - 'ts_paint', 22:19:37 INFO - 'tpaint', 22:19:37 INFO - 'sessionrestore', 22:19:37 INFO - 'sessionrestore_no_auto_restore', 22:19:37 INFO - 'tabpaint']}, 22:19:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:19:37 INFO - 'tests': ['a11yr', 22:19:37 INFO - 'ts_paint', 22:19:37 INFO - 'tpaint', 22:19:37 INFO - 'sessionrestore', 22:19:37 INFO - 'sessionrestore_no_auto_restore', 22:19:37 INFO - 'tabpaint']}, 22:19:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:19:37 INFO - 'tests': ['a11yr', 22:19:37 INFO - 'ts_paint', 22:19:37 INFO - 'tpaint', 22:19:37 INFO - 'sessionrestore', 22:19:37 INFO - 'sessionrestore_no_auto_restore', 22:19:37 INFO - 'tabpaint']}, 22:19:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:19:37 INFO - 'tests': ['tsvgx', 22:19:37 INFO - 'tsvgr_opacity', 22:19:37 INFO - 'tart', 22:19:37 INFO - 'tscrollx', 22:19:37 INFO - 'cart', 22:19:37 INFO - 'tsvg_static']}, 22:19:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:19:37 INFO - 'tsvgr_opacity', 22:19:37 INFO - 'tart', 22:19:37 INFO - 'tscrollx', 22:19:37 INFO - 'cart', 22:19:37 INFO - 'tsvg_static']}, 22:19:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:19:37 INFO - 'talos_options': ['--disable-e10s'], 22:19:37 INFO - 'tests': ['tp5o']}, 22:19:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:19:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:19:37 INFO - 'talos_options': ['--disable-e10s', 22:19:37 INFO - '--xperf_path', 22:19:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:19:37 INFO - 'tests': ['tp5n']}, 22:19:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:19:37 INFO - 'talos_options': ['--xperf_path', 22:19:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:19:37 INFO - 'tests': ['tp5n']}}, 22:19:37 INFO - 'talos.zip': {'path': '', 22:19:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:19:37 INFO - Running post-action listener: _resource_record_post_action 22:19:37 INFO - [mozharness: 2017-03-17 05:19:37.866654Z] Finished populate-webroot step (success) 22:19:37 INFO - [mozharness: 2017-03-17 05:19:37.866765Z] Running create-virtualenv step. 22:19:37 INFO - Running pre-action listener: _resource_record_pre_action 22:19:37 INFO - Running main action method: create_virtualenv 22:19:37 INFO - Creating virtualenv /builds/slave/test/build/venv 22:19:37 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 22:19:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:19:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:19:37 INFO - Using real prefix '/tools/python27' 22:19:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:19:38 INFO - Installing distribute.............................................................................................................................................................................................done. 22:19:41 INFO - Installing pip.................done. 22:19:41 INFO - Return code: 0 22:19:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:19:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:19:41 INFO - Reading from file tmpfile_stdout 22:19:41 INFO - Output received: 22:19:41 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) 22:19:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:19:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:19:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eda768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb383d75670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cb5d30>, '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 0x106bdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106caabe8>, '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.OhrXOb8r1c/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.7rEbpN27Jw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 22:19:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:19:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:19:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OhrXOb8r1c/Render', 22:19:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:19:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:19:41 INFO - 'HOME': '/Users/cltbld', 22:19:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:19:41 INFO - 'LOGNAME': 'cltbld', 22:19:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:41 INFO - 'MOZ_NO_REMOTE': '1', 22:19:41 INFO - 'NO_EM_RESTART': '1', 22:19:41 INFO - 'PAGER': '/bin/cat', 22:19:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:19:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:41 INFO - 'PWD': '/builds/slave/test', 22:19:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:19:41 INFO - 'SHELL': '/bin/bash', 22:19:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7rEbpN27Jw/Listeners', 22:19:41 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 22:19:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:19:41 INFO - 'USER': 'cltbld', 22:19:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:19:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:19:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:41 INFO - 'XPC_FLAGS': '0x0', 22:19:41 INFO - 'XPC_SERVICE_NAME': '0', 22:19:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:19:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:42 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:19:42 INFO - Cleaning up... 22:19:42 INFO - Return code: 0 22:19:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:19: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')]} 22:19:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19: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')]} 22:19:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eda768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb383d75670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cb5d30>, '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 0x106bdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106caabe8>, '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.OhrXOb8r1c/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.7rEbpN27Jw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 22:19:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:19:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:19:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OhrXOb8r1c/Render', 22:19:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:19:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:19:42 INFO - 'HOME': '/Users/cltbld', 22:19:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:19:42 INFO - 'LOGNAME': 'cltbld', 22:19:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:42 INFO - 'MOZ_NO_REMOTE': '1', 22:19:42 INFO - 'NO_EM_RESTART': '1', 22:19:42 INFO - 'PAGER': '/bin/cat', 22:19:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:19:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:42 INFO - 'PWD': '/builds/slave/test', 22:19:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:19:42 INFO - 'SHELL': '/bin/bash', 22:19:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7rEbpN27Jw/Listeners', 22:19:42 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 22:19:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:19:42 INFO - 'USER': 'cltbld', 22:19:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:19:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:19:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:42 INFO - 'XPC_FLAGS': '0x0', 22:19:42 INFO - 'XPC_SERVICE_NAME': '0', 22:19:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:19:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:42 INFO - Downloading/unpacking psutil>=3.1.1 22:19: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 22:19: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 22:19: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 22:19: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 22:19: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 22:19: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 22:19:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:19:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:19:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:19:45 INFO - Installing collected packages: psutil 22:19:45 INFO - Running setup.py install for psutil 22:19:45 INFO - building 'psutil._psutil_osx' extension 22:19:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:19:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:19:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 22:19:46 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 22:19:46 INFO - building 'psutil._psutil_posix' extension 22:19:46 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 22:19:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:19:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:19:46 INFO - ^ 22:19:46 INFO - 1 warning generated. 22:19:46 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 22:19:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:19:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:19:47 INFO - Successfully installed psutil 22:19:47 INFO - Cleaning up... 22:19:47 INFO - Return code: 0 22:19:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:19:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eda768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb383d75670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cb5d30>, '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 0x106bdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106caabe8>, '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.OhrXOb8r1c/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.7rEbpN27Jw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 22:19:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:19:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:19:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OhrXOb8r1c/Render', 22:19:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:19:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:19:47 INFO - 'HOME': '/Users/cltbld', 22:19:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:19:47 INFO - 'LOGNAME': 'cltbld', 22:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:47 INFO - 'MOZ_NO_REMOTE': '1', 22:19:47 INFO - 'NO_EM_RESTART': '1', 22:19:47 INFO - 'PAGER': '/bin/cat', 22:19:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:47 INFO - 'PWD': '/builds/slave/test', 22:19:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:19:47 INFO - 'SHELL': '/bin/bash', 22:19:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7rEbpN27Jw/Listeners', 22:19:47 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 22:19:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:19:47 INFO - 'USER': 'cltbld', 22:19:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:19:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:47 INFO - 'XPC_FLAGS': '0x0', 22:19:47 INFO - 'XPC_SERVICE_NAME': '0', 22:19:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:19:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:19:47 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 22:19:47 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 22:19:47 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 22:19:47 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 22:19:47 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 22:19:47 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 22:19:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:19:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:19:50 INFO - Installing collected packages: mozsystemmonitor 22:19:50 INFO - Running setup.py install for mozsystemmonitor 22:19:50 INFO - Successfully installed mozsystemmonitor 22:19:50 INFO - Cleaning up... 22:19:50 INFO - Return code: 0 22:19:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:19:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eda768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb383d75670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cb5d30>, '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 0x106bdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106caabe8>, '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.OhrXOb8r1c/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.7rEbpN27Jw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 22:19:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:19:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OhrXOb8r1c/Render', 22:19:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:19:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:19:50 INFO - 'HOME': '/Users/cltbld', 22:19:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:19:50 INFO - 'LOGNAME': 'cltbld', 22:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:50 INFO - 'MOZ_NO_REMOTE': '1', 22:19:50 INFO - 'NO_EM_RESTART': '1', 22:19:50 INFO - 'PAGER': '/bin/cat', 22:19:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:50 INFO - 'PWD': '/builds/slave/test', 22:19:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:19:50 INFO - 'SHELL': '/bin/bash', 22:19:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7rEbpN27Jw/Listeners', 22:19:50 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 22:19:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:19:50 INFO - 'USER': 'cltbld', 22:19:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:19:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:50 INFO - 'XPC_FLAGS': '0x0', 22:19:50 INFO - 'XPC_SERVICE_NAME': '0', 22:19:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:50 INFO - Downloading/unpacking jsonschema==2.5.1 22:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:19:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:53 INFO - Downloading functools32-3.2.3-2.tar.gz 22:19:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:19:53 INFO - warning: no files found matching '*.txt' 22:19:53 INFO - no previously-included directories found matching 'build' 22:19:53 INFO - no previously-included directories found matching 'dist' 22:19:53 INFO - no previously-included directories found matching '.git*' 22:19:53 INFO - Installing collected packages: jsonschema, functools32 22:19:53 INFO - Running setup.py install for functools32 22:19:53 INFO - warning: no files found matching '*.txt' 22:19:53 INFO - no previously-included directories found matching 'build' 22:19:53 INFO - no previously-included directories found matching 'dist' 22:19:53 INFO - no previously-included directories found matching '.git*' 22:19:53 INFO - Successfully installed jsonschema functools32 22:19:53 INFO - Cleaning up... 22:19:54 INFO - Return code: 0 22:19:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:19:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eda768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb383d75670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cb5d30>, '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 0x106bdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106caabe8>, '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.OhrXOb8r1c/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.7rEbpN27Jw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 22:19:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:19:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:19:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OhrXOb8r1c/Render', 22:19:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:19:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:19:54 INFO - 'HOME': '/Users/cltbld', 22:19:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:19:54 INFO - 'LOGNAME': 'cltbld', 22:19:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:54 INFO - 'MOZ_NO_REMOTE': '1', 22:19:54 INFO - 'NO_EM_RESTART': '1', 22:19:54 INFO - 'PAGER': '/bin/cat', 22:19:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:19:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:54 INFO - 'PWD': '/builds/slave/test', 22:19:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:19:54 INFO - 'SHELL': '/bin/bash', 22:19:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7rEbpN27Jw/Listeners', 22:19:54 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 22:19:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:19:54 INFO - 'USER': 'cltbld', 22:19:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:19:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:19:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:54 INFO - 'XPC_FLAGS': '0x0', 22:19:54 INFO - 'XPC_SERVICE_NAME': '0', 22:19:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:19:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:19:54 INFO - Cleaning up... 22:19:54 INFO - Return code: 0 22:19:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:19:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eda768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb383d75670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cb5d30>, '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 0x106bdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106caabe8>, '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.OhrXOb8r1c/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.7rEbpN27Jw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 22:19:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:19:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:19:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OhrXOb8r1c/Render', 22:19:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:19:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:19:54 INFO - 'HOME': '/Users/cltbld', 22:19:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:19:54 INFO - 'LOGNAME': 'cltbld', 22:19:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:54 INFO - 'MOZ_NO_REMOTE': '1', 22:19:54 INFO - 'NO_EM_RESTART': '1', 22:19:54 INFO - 'PAGER': '/bin/cat', 22:19:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:19:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:54 INFO - 'PWD': '/builds/slave/test', 22:19:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:19:54 INFO - 'SHELL': '/bin/bash', 22:19:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7rEbpN27Jw/Listeners', 22:19:54 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 22:19:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:19:54 INFO - 'USER': 'cltbld', 22:19:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:19:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:19:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:54 INFO - 'XPC_FLAGS': '0x0', 22:19:54 INFO - 'XPC_SERVICE_NAME': '0', 22:19:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:19:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:54 INFO - Downloading/unpacking blobuploader==1.2.4 22:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:57 INFO - Downloading blobuploader-1.2.4.tar.gz 22:19:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:19:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:19: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 22:19: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 22:19: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 22:19: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 22:19: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 22:19: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 22:19:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:19:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:19: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 22:19: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 22:19: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 22:19: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 22:19: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 22:19: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 22:19:58 INFO - Downloading docopt-0.6.1.tar.gz 22:19:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:19:58 INFO - Installing collected packages: blobuploader, requests, docopt 22:19:58 INFO - Running setup.py install for blobuploader 22:19:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:19:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:19:58 INFO - Running setup.py install for requests 22:19:59 INFO - Running setup.py install for docopt 22:19:59 INFO - Successfully installed blobuploader requests docopt 22:19:59 INFO - Cleaning up... 22:19:59 INFO - Return code: 0 22:19:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eda768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb383d75670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cb5d30>, '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 0x106bdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106caabe8>, '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.OhrXOb8r1c/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.7rEbpN27Jw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 22:19:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:19:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OhrXOb8r1c/Render', 22:19:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:19:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:19:59 INFO - 'HOME': '/Users/cltbld', 22:19:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:19:59 INFO - 'LOGNAME': 'cltbld', 22:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:59 INFO - 'MOZ_NO_REMOTE': '1', 22:19:59 INFO - 'NO_EM_RESTART': '1', 22:19:59 INFO - 'PAGER': '/bin/cat', 22:19:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:59 INFO - 'PWD': '/builds/slave/test', 22:19:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:19:59 INFO - 'SHELL': '/bin/bash', 22:19:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7rEbpN27Jw/Listeners', 22:19:59 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 22:19:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:19:59 INFO - 'USER': 'cltbld', 22:19:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:19:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:59 INFO - 'XPC_FLAGS': '0x0', 22:19:59 INFO - 'XPC_SERVICE_NAME': '0', 22:19:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:19:59 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-m9nJDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:19:59 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-PEbeVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:19:59 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-LlLLUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:19:59 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-VNoIzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:20:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-9bJ7oc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:20:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-e1oLIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:20:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-LMHTkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:20:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-AfhiIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:20:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-N0BSjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:20:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-aUytzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:20:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-ksJJBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:20:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-r0xIGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:20:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-QKACrz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:20:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-gXM6y7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:20:01 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-8gwuLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:20:01 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Guxr0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:20:01 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-xAXRLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:20:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:20:01 INFO - Running setup.py install for manifestparser 22:20:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:20:01 INFO - Running setup.py install for mozcrash 22:20:01 INFO - Running setup.py install for mozdebug 22:20:01 INFO - Running setup.py install for mozdevice 22:20:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:20:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:20:01 INFO - Running setup.py install for mozfile 22:20:02 INFO - Running setup.py install for mozhttpd 22:20:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:20:02 INFO - Running setup.py install for mozinfo 22:20:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:20:02 INFO - Running setup.py install for mozInstall 22:20:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:20:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:20:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:20:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:20:02 INFO - Running setup.py install for mozleak 22:20:02 INFO - Running setup.py install for mozlog 22:20:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:20:02 INFO - Running setup.py install for moznetwork 22:20:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:20:03 INFO - Running setup.py install for mozprocess 22:20:03 INFO - Running setup.py install for mozprofile 22:20:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:20:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:20:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:20:03 INFO - Running setup.py install for mozrunner 22:20:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:20:03 INFO - Running setup.py install for mozscreenshot 22:20:03 INFO - Running setup.py install for moztest 22:20:03 INFO - Running setup.py install for mozversion 22:20:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:20:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:20:03 INFO - Cleaning up... 22:20:03 INFO - Return code: 0 22:20:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:20:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eda768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb383d75670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cb5d30>, '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 0x106bdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106caabe8>, '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.OhrXOb8r1c/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.7rEbpN27Jw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 22:20:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:20:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:20:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OhrXOb8r1c/Render', 22:20:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:20:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:20:03 INFO - 'HOME': '/Users/cltbld', 22:20:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:20:03 INFO - 'LOGNAME': 'cltbld', 22:20:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:03 INFO - 'MOZ_NO_REMOTE': '1', 22:20:03 INFO - 'NO_EM_RESTART': '1', 22:20:03 INFO - 'PAGER': '/bin/cat', 22:20:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:20:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:03 INFO - 'PWD': '/builds/slave/test', 22:20:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:20:03 INFO - 'SHELL': '/bin/bash', 22:20:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7rEbpN27Jw/Listeners', 22:20:03 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 22:20:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:20:03 INFO - 'USER': 'cltbld', 22:20:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:20:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:20:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:03 INFO - 'XPC_FLAGS': '0x0', 22:20:03 INFO - 'XPC_SERVICE_NAME': '0', 22:20:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:20:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:20:04 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-rkd1KB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:20:04 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-JGfN3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:20:04 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-6Cg8sn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:20:04 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-1guySH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:20:04 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)) 22:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:20:04 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-83h8MY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:20:04 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-IhCi8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:20:04 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-7GW_Oa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:20:04 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-LCp6J8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:20:05 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)) 22:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:20:05 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Blr44Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:20:05 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-2UZrGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:20:05 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-wF6yIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:20:05 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Ea2nH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 22:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:20:05 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-vL1As9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:20:05 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-vZhzGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:20:05 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-TSaJL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:20:05 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-cSnT3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:20:05 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)) 22:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:20:05 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-M8LOQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:20:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:20:06 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 22:20:06 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 22:20:06 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 22:20:06 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 22:20:06 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 22:20:06 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 22:20:08 INFO - Downloading blessings-1.6.tar.gz 22:20:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:20:08 INFO - Installing collected packages: blessings 22:20:08 INFO - Running setup.py install for blessings 22:20:08 INFO - Successfully installed blessings 22:20:08 INFO - Cleaning up... 22:20:09 INFO - Return code: 0 22:20:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:20:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:20:09 INFO - Reading from file tmpfile_stdout 22:20:09 INFO - Current package versions: 22:20:09 INFO - blessings == 1.6 22:20:09 INFO - blobuploader == 1.2.4 22:20:09 INFO - docopt == 0.6.1 22:20:09 INFO - functools32 == 3.2.3-2 22:20:09 INFO - jsonschema == 2.5.1 22:20:09 INFO - manifestparser == 1.1 22:20:09 INFO - mozInstall == 1.12 22:20:09 INFO - mozcrash == 1.0 22:20:09 INFO - mozdebug == 0.1 22:20:09 INFO - mozdevice == 0.49 22:20:09 INFO - mozfile == 1.2 22:20:09 INFO - mozhttpd == 0.7 22:20:09 INFO - mozinfo == 0.9 22:20:09 INFO - mozleak == 0.1 22:20:09 INFO - mozlog == 3.4 22:20:09 INFO - moznetwork == 0.27 22:20:09 INFO - mozprocess == 0.24 22:20:09 INFO - mozprofile == 0.28 22:20:09 INFO - mozrunner == 6.13 22:20:09 INFO - mozscreenshot == 0.1 22:20:09 INFO - mozsystemmonitor == 0.3 22:20:09 INFO - moztest == 0.8 22:20:09 INFO - mozversion == 1.4 22:20:09 INFO - psutil == 3.1.1 22:20:09 INFO - requests == 1.2.3 22:20:09 INFO - wsgiref == 0.1.2 22:20:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:20:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:10 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 0x106ed7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eda768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb383d75670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cb5d30>, '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 0x106bdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106caabe8>, '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.OhrXOb8r1c/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.7rEbpN27Jw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 22:20:10 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 22:20:10 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 22:20:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OhrXOb8r1c/Render', 22:20:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:20:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:20:10 INFO - 'HOME': '/Users/cltbld', 22:20:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:20:10 INFO - 'LOGNAME': 'cltbld', 22:20:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:10 INFO - 'MOZ_NO_REMOTE': '1', 22:20:10 INFO - 'NO_EM_RESTART': '1', 22:20:10 INFO - 'PAGER': '/bin/cat', 22:20:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:20:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:10 INFO - 'PWD': '/builds/slave/test', 22:20:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:20:10 INFO - 'SHELL': '/bin/bash', 22:20:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7rEbpN27Jw/Listeners', 22:20:10 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 22:20:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:20:10 INFO - 'USER': 'cltbld', 22:20:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:20:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:20:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:10 INFO - 'XPC_FLAGS': '0x0', 22:20:10 INFO - 'XPC_SERVICE_NAME': '0', 22:20:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:20:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:20:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:20:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:20:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:20:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:20:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:20:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:20:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:20:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:20:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:20:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:20:10 INFO - Cleaning up... 22:20:10 INFO - Return code: 0 22:20:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:20:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:20:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106ed7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eda768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb383d75670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cb5d30>, '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 0x106bdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106caabe8>, '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.OhrXOb8r1c/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.7rEbpN27Jw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 22:20:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:20:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:20:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OhrXOb8r1c/Render', 22:20:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:20:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:20:10 INFO - 'HOME': '/Users/cltbld', 22:20:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:20:10 INFO - 'LOGNAME': 'cltbld', 22:20:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:10 INFO - 'MOZ_NO_REMOTE': '1', 22:20:10 INFO - 'NO_EM_RESTART': '1', 22:20:10 INFO - 'PAGER': '/bin/cat', 22:20:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:20:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:10 INFO - 'PWD': '/builds/slave/test', 22:20:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:20:10 INFO - 'SHELL': '/bin/bash', 22:20:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7rEbpN27Jw/Listeners', 22:20:10 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 22:20:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:20:10 INFO - 'USER': 'cltbld', 22:20:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:20:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:20:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:10 INFO - 'XPC_FLAGS': '0x0', 22:20:10 INFO - 'XPC_SERVICE_NAME': '0', 22:20:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:20:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:20:10 INFO - Cleaning up... 22:20:10 INFO - Return code: 0 22:20:10 INFO - Running post-action listener: _resource_record_post_action 22:20:10 INFO - Running post-action listener: _start_resource_monitoring 22:20:10 INFO - Starting resource monitoring. 22:20:10 INFO - [mozharness: 2017-03-17 05:20:10.990849Z] Finished create-virtualenv step (success) 22:20:10 INFO - [mozharness: 2017-03-17 05:20:10.991771Z] Running install step. 22:20:10 INFO - Running pre-action listener: _resource_record_pre_action 22:20:10 INFO - Running main action method: install 22:20:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:20:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:20:11 INFO - Reading from file tmpfile_stdout 22:20:11 INFO - Detecting whether we're running mozinstall >=1.0... 22:20:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:20:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:20:11 INFO - Reading from file tmpfile_stdout 22:20:11 INFO - Output received: 22:20:11 INFO - Usage: mozinstall [options] installer 22:20:11 INFO - Options: 22:20:11 INFO - -h, --help show this help message and exit 22:20:11 INFO - -d DEST, --destination=DEST 22:20:11 INFO - Directory to install application into. [default: 22:20:11 INFO - "/builds/slave/test"] 22:20:11 INFO - --app=APP Application being installed. [default: firefox] 22:20:11 INFO - mkdir: /builds/slave/test/build/application 22:20:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:20:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 22:20:25 INFO - Reading from file tmpfile_stdout 22:20:25 INFO - Output received: 22:20:25 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 22:20:25 INFO - Running post-action listener: _resource_record_post_action 22:20:25 INFO - [mozharness: 2017-03-17 05:20:25.944086Z] Finished install step (success) 22:20:25 INFO - [mozharness: 2017-03-17 05:20:25.944218Z] Running run-tests step. 22:20:25 INFO - Running pre-action listener: _resource_record_pre_action 22:20:25 INFO - Running main action method: run_tests 22:20:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:20:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:20:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:20:25 INFO - 2017-03-16 22:20:25.958 screenresolution[1623:7198] starting screenresolution argv=screenresolution get 22:20:25 INFO - 2017-03-16 22:20:25.960 screenresolution[1623:7198] Display 0: 1600x1200x32@60 22:20:25 INFO - 2017-03-16 22:20:25.966 screenresolution[1624:7200] starting screenresolution argv=screenresolution list 22:20:25 INFO - Available Modes on Display 0 22:20:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:20:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:20:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:20:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:20:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:20:26 INFO - Intel Iris: 22:20:26 INFO - Chipset Model: Intel Iris 22:20:26 INFO - Type: GPU 22:20:26 INFO - Bus: Built-In 22:20:26 INFO - VRAM (Dynamic, Max): 1536 MB 22:20:26 INFO - Vendor: Intel (0x8086) 22:20:26 INFO - Device ID: 0x0a2e 22:20:26 INFO - Revision ID: 0x0009 22:20:26 INFO - Displays: 22:20:26 INFO - Display: 22:20:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:20:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:20:26 INFO - Display Serial Number: 1600x1200 60 22:20:26 INFO - Main Display: Yes 22:20:26 INFO - Mirror: Off 22:20:26 INFO - Online: Yes 22:20:26 INFO - Rotation: Supported 22:20:26 INFO - Return code: 0 22:20:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:20:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:20:26 INFO - Python 2.7.3 22:20:26 INFO - Return code: 0 22:20:26 INFO - grabbing minidump binary from tooltool 22:20: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')]} 22:20:26 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 0x106cb5d30>, '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 0x106bdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106caabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:20:26 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 22:20:26 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 22:20:26 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 22:20:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:20:26 INFO - Return code: 0 22:20:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:20:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:20:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:20:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:20:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:20:26 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/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--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 22:20:26 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/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0070 --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 22:20:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OhrXOb8r1c/Render', 22:20:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:20:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:20:26 INFO - 'HOME': '/Users/cltbld', 22:20:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:20:26 INFO - 'LOGNAME': 'cltbld', 22:20:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:20:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:20:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:26 INFO - 'MOZ_NO_REMOTE': '1', 22:20:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:20:26 INFO - 'NO_EM_RESTART': '1', 22:20:26 INFO - 'PAGER': '/bin/cat', 22:20:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:20:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:26 INFO - 'PWD': '/builds/slave/test', 22:20:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:20:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:20:26 INFO - 'SHELL': '/bin/bash', 22:20:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7rEbpN27Jw/Listeners', 22:20:26 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 22:20:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:20:26 INFO - 'USER': 'cltbld', 22:20:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:20:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:20:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:26 INFO - 'XPC_FLAGS': '0x0', 22:20:26 INFO - 'XPC_SERVICE_NAME': '0', 22:20:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:20:26 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/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--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 22:20:26 INFO - mozversion application_buildid: 20170316212021 22:20:26 INFO - mozversion application_changeset: 20f404abbe681ecb3203095c7583354d54302adf 22:20:26 INFO - mozversion application_display_name: Firefox 22:20:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:20:26 INFO - mozversion application_name: Firefox 22:20:26 INFO - mozversion application_remotingname: firefox 22:20:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 22:20:26 INFO - mozversion application_vendor: Mozilla 22:20:26 INFO - mozversion application_version: 53.0 22:20:26 INFO - mozversion platform_buildid: 20170316212021 22:20:26 INFO - mozversion platform_changeset: 20f404abbe681ecb3203095c7583354d54302adf 22:20:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 22:20:26 INFO - mozversion platform_version: 53.0 22:20:26 INFO - using testdate: 1489728026 22:20:26 INFO - actual date: 1489728026 22:20:26 INFO - starting webserver on 'localhost:49236' 22:20:26 INFO - SUITE-START | Running 2 tests 22:20:26 INFO - TEST-START | dromaeo_css 22:20:26 INFO - operating with platform_type : mac_ 22:20:26 INFO - Initialising browser for dromaeo_css test... 22:20:26 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpmjnwZW/profile http://localhost:49236/getInfo.html) 22:20:29 INFO - PROCESS | 1634 | 2017-03-16 22:20:29.232 plugin-container[1637:7368] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 22:20:29 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:20:29 INFO - PROCESS | 1634 | 2017-03-16 22:20:29.233 plugin-container[1637:7368] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x911f, name = 'com.apple.CFPasteboardClient' 22:20:29 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:20:29 INFO - PROCESS | 1634 | 2017-03-16 22:20:29.233 plugin-container[1637:7368] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:20:29 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 22:20:29 INFO - PROCESS | 1634 | colorDepth:24 22:20:29 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 22:20:29 INFO - PROCESS | 1634 | __metrics 22:20:29 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 22:20:30 INFO - TEST-INFO | 1634: exit 0 22:20:30 INFO - Browser initialized. 22:20:30 INFO - Running cycle 1/1 for dromaeo_css test... 22:20:30 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpmjnwZW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:20:32 INFO - PROCESS | 1638 | 2017-03-16 22:20:32.253 plugin-container[1639:7496] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x864b, name = 'com.apple.tsm.portname' 22:20:32 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:20:32 INFO - PROCESS | 1638 | 2017-03-16 22:20:32.253 plugin-container[1639:7496] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4417, name = 'com.apple.CFPasteboardClient' 22:20:32 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:20:32 INFO - PROCESS | 1638 | 2017-03-16 22:20:32.253 plugin-container[1639:7496] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:20:41 INFO - PROCESS | 1638 | RSS: Main: 172400640 22:20:41 INFO - PROCESS | 1638 | 22:23:43 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 22:23:45 INFO - PROCESS | 1638 | RSS: Main: 190410752 22:23:45 INFO - PROCESS | 1638 | 22:26:47 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 22:26:48 INFO - PROCESS | 1638 | RSS: Main: 202870784 22:26:48 INFO - PROCESS | 1638 | 22:29:50 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 22:29:50 INFO - PROCESS | 1638 | RSS: Main: 204599296 22:29:50 INFO - PROCESS | 1638 | 22:32:52 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 22:32:53 INFO - PROCESS | 1638 | RSS: Main: 207691776 22:32:53 INFO - PROCESS | 1638 | 22:35:56 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 22:35:56 INFO - PROCESS | 1638 | RSS: Main: 212103168 22:35:56 INFO - PROCESS | 1638 | 22:38:59 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 22:38:59 INFO - PROCESS | 1638 | RSS: Main: 212504576 22:38:59 INFO - PROCESS | 1638 | 22:38:59 INFO - PROCESS | 1638 | __start_tp_report 22:38:59 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 22:38:59 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 22:38:59 INFO - PROCESS | 1638 | |i|pagename|runs| 22:38:59 INFO - PROCESS | 1638 | |0;dojo.html;3530.01159675291;3531.2231309112;3549.50306957026;3556.6265542118076;3569.9107522311956;5927.170196172539;5941.435563621454;5975.462208401243;5985.132155837401;6004.159417681522;6055.818325450233;6112.877742445148;6138.171346868172;6142.508599312056;6182.319944806068;4444.822207111712;5390.757415916293;5458.099413596752;5488.396276409591;5588.944110558883;5134.768935397898;5466.262054622634;5517.36550296715;5518.475744804237;5545.77816887326;5327.387350454689;5339.733013349336;5435.0488664483555;5476.315460567429;5495.2856128703215;5393.379761327439;5414.88994756074;5440.020796256672;5454.318210223722;5514.310711161105;5286.3392075990505;5394.487523685244;5406.324209473816;5409.2967914171295;5450.482204190595;4966.6771659842;5789.739461724233;5973.193618861442;6038.30559485656;6100.969495152496;6281.842953926142;6284.622922624657;6289.937100628981;6293.748250069992;6393.264774550939;6485.805425837185;6498.870022599569;6498.9350106498805;6510.674466276667;6523.478121750249;6181.629102253879;6198.721057552421;6210.751569937197;6224.439800417984;6233.2208473940755;5573.219749235111;6344.555881088279;6359.427651511386;6384.074309225136;6537.803865884031;6260.593061450991;6288.119663247149;6315.242170939517;6330.177076980055;6331.841703957438;6448.9355106449275;6450.838729031811;6454.967725161344;6465.676716164173;6473.967630161913;5111.539961403417;6222.657753823583;6230.657313847782;6261.9373806262265;6284.591501552384;6311.621302721851;6341.302456729756;6367.395097465734;6380.011098279681;6402.743890244431;4646.745378747719;4981.402231732215;5210.166373380317;5213.556847667915;5226.947730522646;4522.841700540465;4585.679002469795;4620.4686461056735;4655.720656760537;4710.199266124767;6107.114468402056;6145.26256849172;6175.641358901034;6207.503399728011;6213.285472170668;6506.934930650727;6516;6518.445932095825;6524.412802847766;6540.803775886731;4907.386576677915;6053.364396738367;6112.351504965227;6112.786052488231;6155.753769849156;4827.710337379768;5658.839937812739;5728.51307638847;5758.424157584208;5767.942320576824;6660.300668429842;6668.499862510234;6678.09845670838;6696.698648560729;6713.6643167842385;6315.305316415099;6330.177076979963;6382.808515744535;6413.230412350548;6439.839004024937;6659.067730517634;6674.26583075871;6686.465082793267;6694;6705.966470167618;6366.4588509977175;6379.52153588492;6388.201474815648;6389.297177310399;6401.135846660642;6238.438540531374;6279.780207692709;6298.748050077946;6461.935380646133;6489.837754056186;6232.781852635136;6268.874622507433;6291.993281075006;6343.55595108338;6347.650879201688;6303.810885673437;6310.242770867525;6324.841878953063;6332.778352757631;6338.239411270677;6173.104899789595;6179;6220.942439785157;6239.812805615657;6296.370362963667;6199.969000154971;6245.531585131224;6270.7491700331475;6309.652969086561;6326.082718005771;6273.96863015682;6288.842778930563;6295.307516173309;6317.241930968313;6326.240851097898 22:38:59 INFO - PROCESS | 1638 | |1;ext.html;446.98659040228796;481.44393225824166;493.29705170132553;495.2942057567965;561.5142901390299;34574.481382779246;34958.825205873996;39974.80012599937;43107.78446107773;43275.350869736976;44019.33970990438;44541.77729111358;44543.331850022274;44637.77681111598;44656;8500.44747091437;9609.279304052206;9887.258455615822;11482.253653512536;11913.702157446047;9811.50942452878;9876.802463950717;11763.235389699685;11820.881791182108;11849;11651.300921944669;11752.52989880408;11802;11843;11859.347735874524;11771.764564708657;11796.823047654292;11820.70448238788;11821.34982575958;11877.406129693481;11480.77038459226;11521.308721476655;11565.306081635128;11569.88430115705;11603.245789023771;38990.44038238467;39044.414333784734;39431.40852887209;39492.60507394918;39525.20949581021;4048.2308361411533;4104.240715467648;4120.196561670476;4160.2303573838935;4295.3771703102875;23198.65202021971;23606.763932360627;29910.85044574785;30204;30812.537811932838;3249.4639523425103;3305.7024867761775;3331.4336562784383;3336.7831090979007;3385.661433856619;434180.8290958525;507457;509092;511291.88708112814;511552;60409;60437.79124417487;60571;60712.48218794477;60715.69642151761;62172;62415.6879215601;62779.686101570114;63001;63088.05367919485;16306.10316432584;16971.406000789913;16981.915090424714;16988.57528561771;17037.914810425867;12071.939640301918;12340.382980850922;12958.22250664963;13099.803502947463;13148.211107333591;16311.836881631032;17215.483535493957;18448.907755461136;18514.722279165824;18574.721379179322;6675.46596272297;7915.208479152154;7967.127724984624;8010.839783204303;8022.83954320922;821.4414198345063;853.568947681417;864.204931463043;871.9346049046347;896.6906417286026;31205.531917021264;31246.843765781025;31267.06198814039;31589.684103159136;31866.36267274688;831.6174559702483;1081.5129197353638;1091.6179337231906;1126.583164229224;1215.4166000319897;16257.674846503003;16267.51197464078;16309.347626095061;16458.93016953894;16590.917045414695;562270;637447;653013;654174;654629;62019.689901550206;62551.06173407403;62780.686096569225;62859.371406286264;62958.370416296166;1417.3692706745605;1456.3155317000992;1465.9413623455155;1477.0694462488564;1487.7098965701584;28674.713252867205;30980.690193098682;31101.377972440878;31489;31592.842035789676;51345.973080537435;51591.226131607305;51634.74182629063;51641.225381618555;51658;506842;517342;519087;519094;520428;25444.236672899842;26166.4766704661;26228.344291392867;26914.730852692006;27656.585151222345;43569.475068372456;43641;44837.77581112074;45199.77400112979;45546.772266138454;36861.44707829438;37131.44302835406;37234.813825930694;37593;38214.426783597715;36773.44839827458;36951.26097477982;37675.05812354713;38137.427938581495;38158.85523434301;37303.44044839275;37541.812290939466;37606.30771615229;38296.42555361616;39029.414558782206;30171;33703;33868.98393048212;34039.149021274665;34255;24736.381590460383;25030.123945661813;25465.49069018572;25502.23493295204;25698.486030279662 22:38:59 INFO - PROCESS | 1638 | |2;jquery.html;361.79196961747;403.2479425870752;407.547622139425;414.45292214277157;441.0583404431538;21101;21492.605892312222;21957.01193446295;22163.556728865413;22453;22303.996320165592;22310.442238944033;22406.439839004033;22793.658095128587;22960.311190664266;5977.2229610150725;6236.345183755709;6285.120083188334;6285.402886725754;6306.117143599877;5966.552508561854;6108.69456527174;6152.415520525543;6152.630842149485;6170.1670274513035;6029.065494848305;6063.545234107438;6096.2074930259005;6146.723397447103;6163.691815409234;5904.1734157218025;6114.449699527042;6121.969390153065;6155.753769849201;6156.692165391735;4586.3808385867815;5275.103232450492;5355.062863998784;5391.29913111293;5455.1544510600925;26864.865675671495;26865.328366790793;27027.729722702916;27055.729442705713;27308;2789.623400840881;2790.2884764384953;2795.1335086123368;2797.9160625181275;2853.671827739816;12182.573609923698;14190.43238270468;16041.839581604152;16197.757033644504;16631.002139871525;1551.4414810668263;1889.7165425186195;1892.3187652445108;1893.8011508791517;1897.3074827687933;122139.77860221373;124928.75071249262;125258;125817;128876.7112328874;9656.32405731592;12010.759784804255;12011;12043.337616431181;12274.815877761841;15358.155301458304;15524.767128493082;15535.456259031105;15540.222988850512;15558.299876505358;9020.503872287087;9259.305552083486;9297.070292970784;9299.34904556688;9352.719418417459;9125.81748365029;9145.131212534798;9155.954220228856;9317.673881414117;9320.06799320076;7599.772006839804;7708.03649543807;7778.727744528915;7779.649915753805;7798.142204357516;1975.604879024172;1993.4318719165258;2004.3185316992292;2006.809353111452;2008.1666108565073;1008.7441135785994;1041.682286902502;1071.4428497181423;1083.951222195003;1105.8728246251624;19964.42575495645;22839.543209136056;22949.770502294763;23001.88499057494;23075.538489230123;1095.315427857589;1099.9945000274947;1104.1994553948484;1132.739469921906;1139.7663478986954;11856.288622682494;11959.342236176919;12044.759104817855;12065.638030859089;12069.698257543632;754449;756111;757235;776597;779764;81628;103779;111970.44014779874;133513.5511185757;138863.30568347295;1072.6299426697776;1077.9299345542515;1108.4346983038547;1111.7109551516503;1112.3214838948236;17217.82782172162;20647.27734529284;21706.34880953574;22011.559768804214;22149.889250553642;56900.71549642225;57699;58065.96769113061;59522.40477595168;59579.404205957384;60858.08712869222;82217;82534.17465825265;82878.17121828704;82954;8258.917410826056;8272.131426200162;8399.328053755808;8529.786755331166;8558.272546833588;9729.562169702238;12641.683957901127;13081.280529570964;13512;13521.256330901502;9056.501892395981;11344.773104538028;11724.648260551856;11798.764024719287;11831.17181797266;9709;11075.446227688744;11175.385353805294;11277;11729.941350293195;11187.664370068911;11460.656180314603;11550.711232218926;11879.406029698308;12033.819492707791;8139.267465928095;8249.236043825045;8520.446170998865;8626.827463450822;8663.350248731496;11290.548378064785;11595.014423774073;11604.6518604442;11624.82562761576;11631.185816992729 22:38:59 INFO - PROCESS | 1638 | |3;mootools.html;1414.151509094544;1470.1987416857812;1485.1979930837347;1488.8436714145007;1489.4637930345077;7779.961100194491;8008.799780005488;8261.545614991173;8707.651693932243;8709.390342676015;8123.228293312126;8419.368547358958;8441.746747597566;8525.062243153248;8541.231289183972;2369.3721163891582;2370.146747171017;2411.7829395354415;2411.8914648840846;2417.528581926525;2199.967000494994;2259.836184365053;2372.264597974625;2421.079989603948;2426.8543887366727;2395.9161429349974;2404.7955923746417;2410.8794560272004;2426.5996110641813;2437.8049756019564;2379.238643634038;2385.689860318165;2395.772401621843;2397.4006498375406;2399.928002159938;2355.729091154505;2372.644103384489;2427.647991041307;2433.4281443860677;2437.9756202437925;8537.871931921027;8610.956945215297;8627.223549880478;8638.65445382184;8663.653453861838;5184.274201611777;5311.362636483608;5327.653702509363;5342.706151161685;5343.091674415358;3531.2584357285;3654.488371627974;3655.4151335786414;3677.9264414711556;3726.4037753959506;1073.2755390111722;1225.5587988324196;1258.7860063789178;1292.8448586169625;1318.6571491412205;11028.28316159455;11032.724181895437;11034.448277586089;11059;11094.00153986145;4680.828325909196;4725.676810493067;4794.017226468565;4817.494163112893;4854.466008739036;9490.525473726286;9635.855462168072;9671.951640241754;9681.467519286507;9698;5343.866403339947;5363.865903352369;5366.543843773323;5375.274337964324;5382.111951527954;4460.888477788081;4510.977445112753;4784.114938736345;4908.165611845995;4962.652614317035;3516.191276006533;3917.3732202847978;3939.92120157601;4051.9797401012806;4089.1003979124544;1092.8032954068185;1212.496813822254;1321.4119716725966;1324.5165514587106;1334.4061892457958;749.5615065186818;794.1700922535964;810.9675612975415;817.4808996287281;817.7301490508119;4692;5650.463205995424;5716.42835716425;5748.770049198069;5790.478856902899;509.96477151382754;570.287141073658;593.6912805341285;599.7960693364278;604.9848753781102;3634.636536346397;3735.9252814943547;3736.252749450121;3743.9625603744157;3856.2094770571966;9960.601575937026;9978.251631127549;10127.088562029452;10273.589056437808;10286.948565257126;9288.931772846074;9452.52737363129;10034.397936123856;10244.59021639141;10244.692659220234;6370.617762934239;7974.122846486998;8036.919630803734;8045.83908321842;8048.396370272253;5471.425500322409;5528.9447105530035;5615.747291371979;5676.914846277392;5695.943040569541;9123.087691230823;9323.766905827137;9423.528823558932;9436.150746432853;9501.097395747393;9115.544222788967;9421.811563768822;9432.717018489457;9486.857697134687;9508.429494230368;6813.659317034029;6863.519553631198;6990.545614535039;7300;7475.327220549959;7951.642176102205;8204.220599043081;8209.71266005687;8214;8215;7292.197858235696;7341.4860959732305;7391.778246652609;7477.364424024019;7503.362214211853;7185.676644551112;7233.276672332725;7260.600666963368;7382.151052629017;7388.076490438695;6435.774747883802;6563;6569.835754105994;6580.21037475506;6585.868282634417;4436.689431739876;4456.554344565518;4509.391232183744;4516;4561.452625684939;4169.749815011109;4999.550040496373;5005.374328208974;5141.485851414828;5256.947430525799 22:38:59 INFO - PROCESS | 1638 | |4;prototype.html;372.5380528145101;373.21065945525214;380.53004539393845;385.1238432565914;387.2931899283806;5230.895382092356;5317.2821669074665;5420.647657902234;5434.130539113743;5524.116141417374;4258;5102.515261050204;5404.7838086476495;5457.617966742329;5475.288212532365;4292.896480724454;4321.827126914927;4325.048489332342;4340.958170039199;4349.630281426083;3495.685388315051;4292.14157168566;4309.698321117523;4321.178975994567;4358.1283743251315;3648.726345524083;3713.6472035156617;4275.551067137937;4278.422412974255;4286;3809.561900381464;4295.355696645497;4300.440942677447;4308.676849236304;4319.460067491564;3807.9619203807883;3808.485854409647;3812.3137835189655;3816.271365973342;3829.3872980323295;4250.702450828443;5089.312942752718;5347.454663535963;5382.416440108731;5421.698792289862;3960.920781584352;4655.906881862377;4953.777080031407;4964.932539504024;5008.924866127011;4851.442084160331;5152.072626927152;5701.572382071361;5837.387074357174;5873.941260587382;3379.0369744622662;3496.685298323138;3970.602939706035;3971.6425521703186;4002.4996875390575;5512.614117011811;5954.255718035246;6116.602420842631;6127.947137708873;6159.38406159385;3546.113471632092;3641.851307728453;3958.782266975344;4042.595740425934;4046.716729828943;3894.0240225396556;4062.898427539276;4083.387491876242;4090.6318431341324;4116.670666346685;4102.118044620421;4537.4328208973875;4704.129735998851;4762.166620841339;4763.071201115818;5537.252470916455;5775.393583673737;5852.531797456193;5879.52963762898;5921.572155416345;1237.300924977385;1274.413769665964;1291.2381694799926;1317.848447428558;1334.6129622409587;866.4368160695599;875.9094926816027;891.9643214271487;905.3798148268457;906.4923642760075;4213.9578604214175;4427.867163985085;4815.95184048155;4918.884879501848;5178.559822415137;4933.703977761345;5359.713668719479;5753.683547404933;5810.82567522975;5832.766689332379;4352.695311328177;4769.855234743636;4873.122837889214;5183.352080989876;5202.930271140046;2936.9118926432243;2988.865501052458;3025.909222723322;3030.817980987417;3044.604201453797;5284.248183789552;5318.680879147263;5877.706114694248;5988.880222395528;6012.939870601325;5194.830808068325;5471.425500322488;5875.941240587625;5928.436798504135;5992.471919660458;5183.352080989876;5619.353774316006;5648.774049038075;6017.398260173947;6046.637201767908;4465.866024019155;5784.942150578609;5791.942080579141;5834.474897259267;5844.824655260349;5307.081105392096;5621.213030175697;5625.943740562706;5731.799387021434;5795.565332599987;4625.468071171858;5082.822101226439;5924.940750592439;5935.584509084495;5974.430994672332;3246.2632491616873;3254.560634314337;3560.181158333546;3570.2145527983803;3759.4398324694944;5079.568236699774;5524.309461317335;5682.261306030189;6028.909566356596;6053.334133245427;5337.6797392156595;5447.891042179213;5492.258545096361;5709.172170035238;5811.912821307599;4803.807847686193;5262.89474210506;5916.201312822714;5918.644881307136;5930.7924222652;4939.382577177853;5098.617603679665;5383.919241211307;5766.509846663081;5806.332271788629;5781.8029148924825;5845;5845.970770146122;5896.027155519291;5934.376890426615;4746.406699162605;5015.824446144367;5293.814716484904;5646.774129034792;5730.169125476866 22:38:59 INFO - PROCESS | 1638 | |5;yui.html;625.5371025441175;738.5937734246163;768.6579472134899;786.4376970466116;895.5164211325884;12476.001919846412;15048.774268385969;16133.19334033298;17087.23107460164;17992.640147197082;13587.93206033971;17088.914555427207;17122.82877171228;17263.48209553712;17442.56393590161;879.4239772948729;904.0688091266006;904.9366544341899;909.2952961454893;918.0131358789329;714.1430283659603;899.2850683706469;900.1943260781629;901.9774505637379;902.7201567514059;763.6601712238056;908.1554154636186;933.4912472702359;942.7360339105062;942.924566034719;925.8148370325928;929.419113054341;929.4794914847639;933.3839665820526;934.53273363318;696.954697944632;699.0178798787712;699.9020137180748;700.0619170311755;705.8270723672733;15617.765733514007;15644.374225030984;15951.282192301374;16015.119168445732;16056.518304450885;14432.278386080654;14846.331915063847;14866.628334291621;14886.925565372212;14895;11750.706232344175;11875;11884.286942783374;11889.643310700692;11920.46357913896;3734.3838266685966;3819.732618716691;3820.4842346283176;3853.402722578005;3976.7415118017525;116162.41918790436;118035.40982295033;119374.40312798467;119572.40213798963;119750.4012479932;10824.621138260281;10825.296355736851;10861.728456788644;10965.287256328313;11064.834027489595;23224.3032709019;23720.762792371854;23764.16825411137;23780.405489862547;23801.523969520513;7917.2874441300555;7977.2022797719765;7977.800554986172;7997.480163789335;8035.839283214303;4070.776107314126;4222.619964203236;4374.934375984363;4469.4636643602325;4608.377868987711;3711.257748450321;3718.9442158367647;3745.194783121642;3790.166163444038;3815.1606646537716;575.493565662223;685.4858855858106;685.67773146621;696.6342670098138;706.4313227851526;546.5136028934172;686.5743239191634;712.590260600157;727.1637616740685;734.0657367835792;9265.583048762823;9513.66702165421;9955.800883982425;10122.190224782;10129.848052279222;638.1225814505055;671.8454755406186;679.2562144451817;725.3435640745134;742.1835980421494;4500.684952053325;4706.694064885772;4726.291056341576;5047.293378926953;5066.82266120684;97766;112187;112241;115751.84248157579;117530;99332;102237;104698;104928.47535762425;105206.47396762967;89086;89186;90592.54703726439;91995.08004919998;92192.53903730438;6988.8951665724035;9459.621615135318;9671.564779584794;9759.512024398893;9781.559829807533;47137.52862471469;52053.7397313011;54432.45567544274;54853.72573137269;56011.43988560062;59378.92173773897;68263.65868170628;69280.30719692739;71633.64183179259;73852.63073684597;11649.534018639159;11681.240719353214;11862.881371186522;12208;12246.816297755362;12528.320677206522;12567.308798016196;12661.113722039368;12993;13144.80282795778;11752.177347585586;11850.992665623518;11943.343116128348;12179.39103044862;12383.504659813507;9688.467134307399;11757;12079.39603019863;12314.261144331369;12440.37798110109;10954;11177.882211778757;12031.819522706992;12339.814902776645;12430.751384972431;9876.851847222153;10221.591136354462;10250.948745256226;10350.948245258725;10620.415877126832;7054.329838665319;8920.73237802867;9959;9965;9984.900150998397 22:38:59 INFO - PROCESS | 1638 | __end_tp_report 22:38:59 INFO - PROCESS | 1638 | __start_cc_report 22:38:59 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,375 22:38:59 INFO - PROCESS | 1638 | __end_cc_report 22:38:59 INFO - PROCESS | 1638 | __startTimestamp1489729139828__endTimestamp 22:38:59 INFO - PROCESS | 1638 | 22:38:59 INFO - PROCESS | 1638 | ------- Summary: start ------- 22:38:59 INFO - PROCESS | 1638 | Number of tests: 6 22:38:59 INFO - PROCESS | 1638 | 22:38:59 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5953.26 Median:6232.78 stddev:676.08 (10.8%) stddev-sans-first:650.88 22:38:59 INFO - PROCESS | 1638 | Values: 3530.0 3531.2 3549.5 3556.6 3569.9 5927.2 5941.4 5975.5 5985.1 6004.2 6055.8 6112.9 6138.2 6142.5 6182.3 4444.8 5390.8 5458.1 5488.4 5588.9 5134.8 5466.3 5517.4 5518.5 5545.8 5327.4 5339.7 5435.0 5476.3 5495.3 5393.4 5414.9 5440.0 5454.3 5514.3 5286.3 5394.5 5406.3 5409.3 5450.5 4966.7 5789.7 5973.2 6038.3 6101.0 6281.8 6284.6 6289.9 6293.7 6393.3 6485.8 6498.9 6498.9 6510.7 6523.5 6181.6 6198.7 6210.8 6224.4 6233.2 5573.2 6344.6 6359.4 6384.1 6537.8 6260.6 6288.1 6315.2 6330.2 6331.8 6448.9 6450.8 6455.0 6465.7 6474.0 5111.5 6222.7 6230.7 6261.9 6284.6 6311.6 6341.3 6367.4 6380.0 6402.7 4646.7 4981.4 5210.2 5213.6 5226.9 4522.8 4585.7 4620.5 4655.7 4710.2 6107.1 6145.3 6175.6 6207.5 6213.3 6506.9 6516.0 6518.4 6524.4 6540.8 4907.4 6053.4 6112.4 6112.8 6155.8 4827.7 5658.8 5728.5 5758.4 5767.9 6660.3 6668.5 6678.1 6696.7 6713.7 6315.3 6330.2 6382.8 6413.2 6439.8 6659.1 6674.3 6686.5 6694.0 6706.0 6366.5 6379.5 6388.2 6389.3 6401.1 6238.4 6279.8 6298.7 6461.9 6489.8 6232.8 6268.9 6292.0 6343.6 6347.7 6303.8 6310.2 6324.8 6332.8 6338.2 6173.1 6179.0 6220.9 6239.8 6296.4 6200.0 6245.5 6270.7 6309.7 6326.1 6274.0 6288.8 6295.3 6317.2 6326.2 22:38:59 INFO - PROCESS | 1638 | 22:38:59 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:68763.44 Median:37269.13 stddev:147207.18 (395.0%) stddev-sans-first:147530.77 22:38:59 INFO - PROCESS | 1638 | Values: 447.0 481.4 493.3 495.3 561.5 34574.5 34958.8 39974.8 43107.8 43275.4 44019.3 44541.8 44543.3 44637.8 44656.0 8500.4 9609.3 9887.3 11482.3 11913.7 9811.5 9876.8 11763.2 11820.9 11849.0 11651.3 11752.5 11802.0 11843.0 11859.3 11771.8 11796.8 11820.7 11821.3 11877.4 11480.8 11521.3 11565.3 11569.9 11603.2 38990.4 39044.4 39431.4 39492.6 39525.2 4048.2 4104.2 4120.2 4160.2 4295.4 23198.7 23606.8 29910.9 30204.0 30812.5 3249.5 3305.7 3331.4 3336.8 3385.7 434180.8 507457.0 509092.0 511291.9 511552.0 60409.0 60437.8 60571.0 60712.5 60715.7 62172.0 62415.7 62779.7 63001.0 63088.1 16306.1 16971.4 16981.9 16988.6 17037.9 12071.9 12340.4 12958.2 13099.8 13148.2 16311.8 17215.5 18448.9 18514.7 18574.7 6675.5 7915.2 7967.1 8010.8 8022.8 821.4 853.6 864.2 871.9 896.7 31205.5 31246.8 31267.1 31589.7 31866.4 831.6 1081.5 1091.6 1126.6 1215.4 16257.7 16267.5 16309.3 16458.9 16590.9 562270.0 637447.0 653013.0 654174.0 654629.0 62019.7 62551.1 62780.7 62859.4 62958.4 1417.4 1456.3 1465.9 1477.1 1487.7 28674.7 30980.7 31101.4 31489.0 31592.8 51346.0 51591.2 51634.7 51641.2 51658.0 506842.0 517342.0 519087.0 519094.0 520428.0 25444.2 26166.5 26228.3 26914.7 27656.6 43569.5 43641.0 44837.8 45199.8 45546.8 36861.4 37131.4 37234.8 37593.0 38214.4 36773.4 36951.3 37675.1 38137.4 38158.9 37303.4 37541.8 37606.3 38296.4 39029.4 30171.0 33703.0 33869.0 34039.1 34255.0 24736.4 25030.1 25465.5 25502.2 25698.5 22:38:59 INFO - PROCESS | 1638 | 22:38:59 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:40288.62 Median:22894.66 stddev:126187.54 (551.2%) stddev-sans-first:126505.91 22:38:59 INFO - PROCESS | 1638 | Values: 361.8 403.2 407.5 414.5 441.1 21101.0 21492.6 21957.0 22163.6 22453.0 22304.0 22310.4 22406.4 22793.7 22960.3 5977.2 6236.3 6285.1 6285.4 6306.1 5966.6 6108.7 6152.4 6152.6 6170.2 6029.1 6063.5 6096.2 6146.7 6163.7 5904.2 6114.4 6122.0 6155.8 6156.7 4586.4 5275.1 5355.1 5391.3 5455.2 26864.9 26865.3 27027.7 27055.7 27308.0 2789.6 2790.3 2795.1 2797.9 2853.7 12182.6 14190.4 16041.8 16197.8 16631.0 1551.4 1889.7 1892.3 1893.8 1897.3 122139.8 124928.8 125258.0 125817.0 128876.7 9656.3 12010.8 12011.0 12043.3 12274.8 15358.2 15524.8 15535.5 15540.2 15558.3 9020.5 9259.3 9297.1 9299.3 9352.7 9125.8 9145.1 9156.0 9317.7 9320.1 7599.8 7708.0 7778.7 7779.6 7798.1 1975.6 1993.4 2004.3 2006.8 2008.2 1008.7 1041.7 1071.4 1084.0 1105.9 19964.4 22839.5 22949.8 23001.9 23075.5 1095.3 1100.0 1104.2 1132.7 1139.8 11856.3 11959.3 12044.8 12065.6 12069.7 754449.0 756111.0 757235.0 776597.0 779764.0 81628.0 103779.0 111970.4 133513.6 138863.3 1072.6 1077.9 1108.4 1111.7 1112.3 17217.8 20647.3 21706.3 22011.6 22149.9 56900.7 57699.0 58066.0 59522.4 59579.4 60858.1 82217.0 82534.2 82878.2 82954.0 8258.9 8272.1 8399.3 8529.8 8558.3 9729.6 12641.7 13081.3 13512.0 13521.3 9056.5 11344.8 11724.6 11798.8 11831.2 9709.0 11075.4 11175.4 11277.0 11729.9 11187.7 11460.7 11550.7 11879.4 12033.8 8139.3 8249.2 8520.4 8626.8 8663.4 11290.5 11595.0 11604.7 11624.8 11631.2 22:38:59 INFO - PROCESS | 1638 | 22:38:59 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5505.18 Median:5335.18 stddev:3021.41 (56.6%) stddev-sans-first:3014.24 22:38:59 INFO - PROCESS | 1638 | Values: 1414.2 1470.2 1485.2 1488.8 1489.5 7780.0 8008.8 8261.5 8707.7 8709.4 8123.2 8419.4 8441.7 8525.1 8541.2 2369.4 2370.1 2411.8 2411.9 2417.5 2200.0 2259.8 2372.3 2421.1 2426.9 2395.9 2404.8 2410.9 2426.6 2437.8 2379.2 2385.7 2395.8 2397.4 2399.9 2355.7 2372.6 2427.6 2433.4 2438.0 8537.9 8611.0 8627.2 8638.7 8663.7 5184.3 5311.4 5327.7 5342.7 5343.1 3531.3 3654.5 3655.4 3677.9 3726.4 1073.3 1225.6 1258.8 1292.8 1318.7 11028.3 11032.7 11034.4 11059.0 11094.0 4680.8 4725.7 4794.0 4817.5 4854.5 9490.5 9635.9 9672.0 9681.5 9698.0 5343.9 5363.9 5366.5 5375.3 5382.1 4460.9 4511.0 4784.1 4908.2 4962.7 3516.2 3917.4 3939.9 4052.0 4089.1 1092.8 1212.5 1321.4 1324.5 1334.4 749.6 794.2 811.0 817.5 817.7 4692.0 5650.5 5716.4 5748.8 5790.5 510.0 570.3 593.7 599.8 605.0 3634.6 3735.9 3736.3 3744.0 3856.2 9960.6 9978.3 10127.1 10273.6 10286.9 9288.9 9452.5 10034.4 10244.6 10244.7 6370.6 7974.1 8036.9 8045.8 8048.4 5471.4 5528.9 5615.7 5676.9 5695.9 9123.1 9323.8 9423.5 9436.2 9501.1 9115.5 9421.8 9432.7 9486.9 9508.4 6813.7 6863.5 6990.5 7300.0 7475.3 7951.6 8204.2 8209.7 8214.0 8215.0 7292.2 7341.5 7391.8 7477.4 7503.4 7185.7 7233.3 7260.6 7382.2 7388.1 6435.8 6563.0 6569.8 6580.2 6585.9 4436.7 4456.6 4509.4 4516.0 4561.5 4169.7 4999.6 5005.4 5141.5 5256.9 22:38:59 INFO - PROCESS | 1638 | 22:38:59 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4601.05 Median:5100.57 stddev:1405.63 (27.6%) stddev-sans-first:1373.27 22:38:59 INFO - PROCESS | 1638 | Values: 372.5 373.2 380.5 385.1 387.3 5230.9 5317.3 5420.6 5434.1 5524.1 4258.0 5102.5 5404.8 5457.6 5475.3 4292.9 4321.8 4325.0 4341.0 4349.6 3495.7 4292.1 4309.7 4321.2 4358.1 3648.7 3713.6 4275.6 4278.4 4286.0 3809.6 4295.4 4300.4 4308.7 4319.5 3808.0 3808.5 3812.3 3816.3 3829.4 4250.7 5089.3 5347.5 5382.4 5421.7 3960.9 4655.9 4953.8 4964.9 5008.9 4851.4 5152.1 5701.6 5837.4 5873.9 3379.0 3496.7 3970.6 3971.6 4002.5 5512.6 5954.3 6116.6 6127.9 6159.4 3546.1 3641.9 3958.8 4042.6 4046.7 3894.0 4062.9 4083.4 4090.6 4116.7 4102.1 4537.4 4704.1 4762.2 4763.1 5537.3 5775.4 5852.5 5879.5 5921.6 1237.3 1274.4 1291.2 1317.8 1334.6 866.4 875.9 892.0 905.4 906.5 4214.0 4427.9 4816.0 4918.9 5178.6 4933.7 5359.7 5753.7 5810.8 5832.8 4352.7 4769.9 4873.1 5183.4 5202.9 2936.9 2988.9 3025.9 3030.8 3044.6 5284.2 5318.7 5877.7 5988.9 6012.9 5194.8 5471.4 5875.9 5928.4 5992.5 5183.4 5619.4 5648.8 6017.4 6046.6 4465.9 5784.9 5791.9 5834.5 5844.8 5307.1 5621.2 5625.9 5731.8 5795.6 4625.5 5082.8 5924.9 5935.6 5974.4 3246.3 3254.6 3560.2 3570.2 3759.4 5079.6 5524.3 5682.3 6028.9 6053.3 5337.7 5447.9 5492.3 5709.2 5811.9 4803.8 5262.9 5916.2 5918.6 5930.8 4939.4 5098.6 5383.9 5766.5 5806.3 5781.8 5845.0 5846.0 5896.0 5934.4 4746.4 5015.8 5293.8 5646.8 5730.2 22:38:59 INFO - PROCESS | 1638 | 22:38:59 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:21795.80 Median:4422.20 stddev:32926.18 (744.6%) stddev-sans-first:32980.17 22:38:59 INFO - PROCESS | 1638 | Values: 625.5 738.6 768.7 786.4 895.5 12476.0 15048.8 16133.2 17087.2 17992.6 13587.9 17088.9 17122.8 17263.5 17442.6 879.4 904.1 904.9 909.3 918.0 714.1 899.3 900.2 902.0 902.7 763.7 908.2 933.5 942.7 942.9 925.8 929.4 929.5 933.4 934.5 697.0 699.0 699.9 700.1 705.8 15617.8 15644.4 15951.3 16015.1 16056.5 14432.3 14846.3 14866.6 14886.9 14895.0 11750.7 11875.0 11884.3 11889.6 11920.5 3734.4 3819.7 3820.5 3853.4 3976.7 116162.4 118035.4 119374.4 119572.4 119750.4 10824.6 10825.3 10861.7 10965.3 11064.8 23224.3 23720.8 23764.2 23780.4 23801.5 7917.3 7977.2 7977.8 7997.5 8035.8 4070.8 4222.6 4374.9 4469.5 4608.4 3711.3 3718.9 3745.2 3790.2 3815.2 575.5 685.5 685.7 696.6 706.4 546.5 686.6 712.6 727.2 734.1 9265.6 9513.7 9955.8 10122.2 10129.8 638.1 671.8 679.3 725.3 742.2 4500.7 4706.7 4726.3 5047.3 5066.8 97766.0 112187.0 112241.0 115751.8 117530.0 99332.0 102237.0 104698.0 104928.5 105206.5 89086.0 89186.0 90592.5 91995.1 92192.5 6988.9 9459.6 9671.6 9759.5 9781.6 47137.5 52053.7 54432.5 54853.7 56011.4 59378.9 68263.7 69280.3 71633.6 73852.6 11649.5 11681.2 11862.9 12208.0 12246.8 12528.3 12567.3 12661.1 12993.0 13144.8 11752.2 11851.0 11943.3 12179.4 12383.5 9688.5 11757.0 12079.4 12314.3 12440.4 10954.0 11177.9 12031.8 12339.8 12430.8 9876.9 10221.6 10250.9 10350.9 10620.4 7054.3 8920.7 9959.0 9965.0 9984.9 22:38:59 INFO - PROCESS | 1638 | -------- Summary: end -------- 22:38:59 INFO - PROCESS | 1638 | 22:39:00 INFO - TEST-INFO | 1638: exit 0 22:39:00 INFO - TEST-OK | dromaeo_css | took 1113980ms 22:39:00 INFO - TEST-START | kraken 22:39:00 INFO - operating with platform_type : mac_ 22:39:00 INFO - Initialising browser for kraken test... 22:39:00 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpzbeodN/profile http://localhost:49236/getInfo.html) 22:39:03 INFO - PROCESS | 1651 | 2017-03-16 22:39:03.197 plugin-container[1653:9752] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 22:39:03 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:39:03 INFO - PROCESS | 1651 | 2017-03-16 22:39:03.198 plugin-container[1653:9752] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8917, name = 'com.apple.CFPasteboardClient' 22:39:03 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:39:03 INFO - PROCESS | 1651 | 2017-03-16 22:39:03.198 plugin-container[1653:9752] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:39:03 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 22:39:03 INFO - PROCESS | 1651 | colorDepth:24 22:39:03 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 22:39:03 INFO - PROCESS | 1651 | __metrics 22:39:03 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 22:39:04 INFO - TEST-INFO | 1651: exit 0 22:39:04 INFO - Browser initialized. 22:39:04 INFO - Running cycle 1/1 for kraken test... 22:39:04 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpzbeodN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:39:06 INFO - PROCESS | 1654 | 2017-03-16 22:39:06.226 plugin-container[1655:9882] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 22:39:06 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:39:06 INFO - PROCESS | 1654 | 2017-03-16 22:39:06.226 plugin-container[1655:9882] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4223, name = 'com.apple.CFPasteboardClient' 22:39:06 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:39:06 INFO - PROCESS | 1654 | 2017-03-16 22:39:06.226 plugin-container[1655:9882] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:39:15 INFO - PROCESS | 1654 | RSS: Main: 172662784 22:39:15 INFO - PROCESS | 1654 | 22:39:18 INFO - PROCESS | 1654 | 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) 22:39:19 INFO - PROCESS | 1654 | RSS: Main: 185470976 22:39:19 INFO - PROCESS | 1654 | 22:39:22 INFO - PROCESS | 1654 | 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) 22:39:22 INFO - PROCESS | 1654 | RSS: Main: 193556480 22:39:22 INFO - PROCESS | 1654 | 22:39:26 INFO - PROCESS | 1654 | 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) 22:39:26 INFO - PROCESS | 1654 | RSS: Main: 197242880 22:39:26 INFO - PROCESS | 1654 | 22:39:28 INFO - PROCESS | 1654 | 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) 22:39:29 INFO - PROCESS | 1654 | RSS: Main: 197869568 22:39:29 INFO - PROCESS | 1654 | 22:39:31 INFO - PROCESS | 1654 | 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) 22:39:31 INFO - PROCESS | 1654 | RSS: Main: 198995968 22:39:31 INFO - PROCESS | 1654 | 22:39:34 INFO - PROCESS | 1654 | 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) 22:39:35 INFO - PROCESS | 1654 | RSS: Main: 203157504 22:39:35 INFO - PROCESS | 1654 | 22:39:38 INFO - PROCESS | 1654 | 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) 22:39:39 INFO - PROCESS | 1654 | RSS: Main: 203911168 22:39:39 INFO - PROCESS | 1654 | 22:39:42 INFO - PROCESS | 1654 | 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) 22:39:43 INFO - PROCESS | 1654 | RSS: Main: 203046912 22:39:43 INFO - PROCESS | 1654 | 22:39:44 INFO - PROCESS | 1654 | 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) 22:39:45 INFO - PROCESS | 1654 | RSS: Main: 201777152 22:39:45 INFO - PROCESS | 1654 | 22:39:47 INFO - PROCESS | 1654 | 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) 22:39:47 INFO - PROCESS | 1654 | RSS: Main: 201764864 22:39:47 INFO - PROCESS | 1654 | 22:39:49 INFO - PROCESS | 1654 | 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) 22:39:50 INFO - PROCESS | 1654 | RSS: Main: 201773056 22:39:50 INFO - PROCESS | 1654 | 22:39:53 INFO - PROCESS | 1654 | 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) 22:39:54 INFO - PROCESS | 1654 | RSS: Main: 201826304 22:39:54 INFO - PROCESS | 1654 | 22:39:57 INFO - PROCESS | 1654 | 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) 22:39:57 INFO - PROCESS | 1654 | RSS: Main: 202178560 22:39:57 INFO - PROCESS | 1654 | 22:39:59 INFO - PROCESS | 1654 | 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) 22:39:59 INFO - PROCESS | 1654 | RSS: Main: 203821056 22:39:59 INFO - PROCESS | 1654 | 22:39:59 INFO - PROCESS | 1654 | __start_tp_report 22:39:59 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 22:39:59 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 22:39:59 INFO - PROCESS | 1654 | |i|pagename|runs| 22:39:59 INFO - PROCESS | 1654 | |0;ai-astar;113;117;118;118;117;117;116;117;117;117 22:39:59 INFO - PROCESS | 1654 | |1;audio-beat-detection;146;146;111;143;113;113;112;110;130;116 22:39:59 INFO - PROCESS | 1654 | |2;audio-dft;195;196;196;194;201;196;198;198;199;192 22:39:59 INFO - PROCESS | 1654 | |3;audio-fft;81;58;64;91;85;59;60;59;71;60 22:39:59 INFO - PROCESS | 1654 | |4;audio-oscillator;88;70;82;74;71;71;81;69;71;81 22:39:59 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;90;88;89;90;89;83;137;88;103;86 22:39:59 INFO - PROCESS | 1654 | |6;imaging-darkroom;99;94;106;155;110;153;109;154;133;150 22:39:59 INFO - PROCESS | 1654 | |7;imaging-desaturate;109;101;98;75;76;143;149;144;92;92 22:39:59 INFO - PROCESS | 1654 | |8;json-parse-financial;57;65;59;61;62;62;62;73;79;61 22:39:59 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;75;68;74;69;67;74;68;71;73;60 22:39:59 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;82;73;90;73;89;69;88;103;70;94 22:39:59 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;137;140;140;146;140;147;155;162;180;156 22:39:59 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;239;203;164;224;163;209;230;208;167;166 22:39:59 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;64;65;65;64;64;68;66;64;63;75 22:39:59 INFO - PROCESS | 1654 | __end_tp_report 22:39:59 INFO - PROCESS | 1654 | __start_cc_report 22:39:59 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,929 22:39:59 INFO - PROCESS | 1654 | __end_cc_report 22:39:59 INFO - PROCESS | 1654 | __startTimestamp1489729199884__endTimestamp 22:39:59 INFO - PROCESS | 1654 | 22:39:59 INFO - PROCESS | 1654 | ------- Summary: start ------- 22:39:59 INFO - PROCESS | 1654 | Number of tests: 14 22:39:59 INFO - PROCESS | 1654 | 22:39:59 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:116.70 Median:117.00 stddev:1.42 (1.2%) stddev-sans-first:0.60 22:39:59 INFO - PROCESS | 1654 | Values: 113.0 117.0 118.0 118.0 117.0 117.0 116.0 117.0 117.0 117.0 22:39:59 INFO - PROCESS | 1654 | 22:39:59 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:124.00 Median:123.00 stddev:15.56 (12.7%) stddev-sans-first:14.33 22:39:59 INFO - PROCESS | 1654 | Values: 146.0 146.0 111.0 143.0 113.0 113.0 112.0 110.0 130.0 116.0 22:39:59 INFO - PROCESS | 1654 | 22:39:59 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:196.50 Median:197.00 stddev:2.59 (1.3%) stddev-sans-first:2.69 22:39:59 INFO - PROCESS | 1654 | Values: 195.0 196.0 196.0 194.0 201.0 196.0 198.0 198.0 199.0 192.0 22:39:59 INFO - PROCESS | 1654 | 22:39:59 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:68.80 Median:67.50 stddev:12.45 (18.4%) stddev-sans-first:12.40 22:39:59 INFO - PROCESS | 1654 | Values: 81.0 58.0 64.0 91.0 85.0 59.0 60.0 59.0 71.0 60.0 22:39:59 INFO - PROCESS | 1654 | 22:39:59 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:75.80 Median:77.50 stddev:6.61 (8.5%) stddev-sans-first:5.34 22:39:59 INFO - PROCESS | 1654 | Values: 88.0 70.0 82.0 74.0 71.0 71.0 81.0 69.0 71.0 81.0 22:39:59 INFO - PROCESS | 1654 | 22:39:59 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:94.30 Median:88.50 stddev:15.87 (17.9%) stddev-sans-first:16.76 22:39:59 INFO - PROCESS | 1654 | Values: 90.0 88.0 89.0 90.0 89.0 83.0 137.0 88.0 103.0 86.0 22:39:59 INFO - PROCESS | 1654 | 22:39:59 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:126.30 Median:153.50 stddev:25.11 (16.4%) stddev-sans-first:24.62 22:39:59 INFO - PROCESS | 1654 | Values: 99.0 94.0 106.0 155.0 110.0 153.0 109.0 154.0 133.0 150.0 22:39:59 INFO - PROCESS | 1654 | 22:39:59 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:107.90 Median:75.50 stddev:27.84 (36.9%) stddev-sans-first:29.53 22:39:59 INFO - PROCESS | 1654 | Values: 109.0 101.0 98.0 75.0 76.0 143.0 149.0 144.0 92.0 92.0 22:39:59 INFO - PROCESS | 1654 | 22:39:59 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:64.10 Median:62.00 stddev:6.76 (10.9%) stddev-sans-first:6.66 22:39:59 INFO - PROCESS | 1654 | Values: 57.0 65.0 59.0 61.0 62.0 62.0 62.0 73.0 79.0 61.0 22:39:59 INFO - PROCESS | 1654 | 22:39:59 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:72.00 stddev:4.53 (6.3%) stddev-sans-first:4.42 22:39:59 INFO - PROCESS | 1654 | Values: 75.0 68.0 74.0 69.0 67.0 74.0 68.0 71.0 73.0 60.0 22:39:59 INFO - PROCESS | 1654 | 22:39:59 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:85.00 stddev:11.53 (13.6%) stddev-sans-first:12.22 22:39:59 INFO - PROCESS | 1654 | Values: 82.0 73.0 90.0 73.0 89.0 69.0 88.0 103.0 70.0 94.0 22:39:59 INFO - PROCESS | 1654 | 22:39:59 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:151.00 stddev:13.33 (8.8%) stddev-sans-first:13.24 22:39:59 INFO - PROCESS | 1654 | Values: 137.0 140.0 140.0 146.0 140.0 147.0 155.0 162.0 180.0 156.0 22:39:59 INFO - PROCESS | 1654 | 22:39:59 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.30 Median:208.50 stddev:29.79 (14.3%) stddev-sans-first:27.51 22:39:59 INFO - PROCESS | 1654 | Values: 239.0 203.0 164.0 224.0 163.0 209.0 230.0 208.0 167.0 166.0 22:39:59 INFO - PROCESS | 1654 | 22:39:59 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:65.00 stddev:3.52 (5.4%) stddev-sans-first:3.67 22:39:59 INFO - PROCESS | 1654 | Values: 64.0 65.0 65.0 64.0 64.0 68.0 66.0 64.0 63.0 75.0 22:39:59 INFO - PROCESS | 1654 | -------- Summary: end -------- 22:39:59 INFO - PROCESS | 1654 | 22:40:00 INFO - TEST-INFO | 1654: exit 0 22:40:00 INFO - TEST-OK | kraken | took 60177ms 22:40:00 INFO - SUITE-END | took 1174s 22:40:01 INFO - Completed test suite (00:19:34) 22:40:01 INFO - javascript benchmark 22:40:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7481.977927363938, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3530.01159675291, 3531.2231309112, 3549.50306957026, 3556.6265542118076, 3569.9107522311956, 5927.170196172539, 5941.435563621454, 5975.462208401243, 5985.132155837401, 6004.159417681522, 6055.818325450233, 6112.877742445148, 6138.171346868172, 6142.508599312056, 6182.319944806068, 4444.822207111712, 5390.757415916293, 5458.099413596752, 5488.396276409591, 5588.944110558883, 5134.768935397898, 5466.262054622634, 5517.36550296715, 5518.475744804237, 5545.77816887326, 5327.387350454689, 5339.733013349336, 5435.0488664483555, 5476.315460567429, 5495.2856128703215, 5393.379761327439, 5414.88994756074, 5440.020796256672, 5454.318210223722, 5514.310711161105, 5286.3392075990505, 5394.487523685244, 5406.324209473816, 5409.2967914171295, 5450.482204190595, 4966.6771659842, 5789.739461724233, 5973.193618861442, 6038.30559485656, 6100.969495152496, 6281.842953926142, 6284.622922624657, 6289.937100628981, 6293.748250069992, 6393.264774550939, 6485.805425837185, 6498.870022599569, 6498.9350106498805, 6510.674466276667, 6523.478121750249, 6181.629102253879, 6198.721057552421, 6210.751569937197, 6224.439800417984, 6233.2208473940755, 5573.219749235111, 6344.555881088279, 6359.427651511386, 6384.074309225136, 6537.803865884031, 6260.593061450991, 6288.119663247149, 6315.242170939517, 6330.177076980055, 6331.841703957438, 6448.9355106449275, 6450.838729031811, 6454.967725161344, 6465.676716164173, 6473.967630161913, 5111.539961403417, 6222.657753823583, 6230.657313847782, 6261.9373806262265, 6284.591501552384, 6311.621302721851, 6341.302456729756, 6367.395097465734, 6380.011098279681, 6402.743890244431, 4646.745378747719, 4981.402231732215, 5210.166373380317, 5213.556847667915, 5226.947730522646, 4522.841700540465, 4585.679002469795, 4620.4686461056735, 4655.720656760537, 4710.199266124767, 6107.114468402056, 6145.26256849172, 6175.641358901034, 6207.503399728011, 6213.285472170668, 6506.934930650727, 6516.0, 6518.445932095825, 6524.412802847766, 6540.803775886731, 4907.386576677915, 6053.364396738367, 6112.351504965227, 6112.786052488231, 6155.753769849156, 4827.710337379768, 5658.839937812739, 5728.51307638847, 5758.424157584208, 5767.942320576824, 6660.300668429842, 6668.499862510234, 6678.09845670838, 6696.698648560729, 6713.6643167842385, 6315.305316415099, 6330.177076979963, 6382.808515744535, 6413.230412350548, 6439.839004024937, 6659.067730517634, 6674.26583075871, 6686.465082793267, 6694.0, 6705.966470167618, 6366.4588509977175, 6379.52153588492, 6388.201474815648, 6389.297177310399, 6401.135846660642, 6238.438540531374, 6279.780207692709, 6298.748050077946, 6461.935380646133, 6489.837754056186, 6232.781852635136, 6268.874622507433, 6291.993281075006, 6343.55595108338, 6347.650879201688, 6303.810885673437, 6310.242770867525, 6324.841878953063, 6332.778352757631, 6338.239411270677, 6173.104899789595, 6179.0, 6220.942439785157, 6239.812805615657, 6296.370362963667, 6199.969000154971, 6245.531585131224, 6270.7491700331475, 6309.652969086561, 6326.082718005771, 6273.96863015682, 6288.842778930563, 6295.307516173309, 6317.241930968313, 6326.240851097898], "value": 5911.575176350678, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [446.98659040228796, 481.44393225824166, 493.29705170132553, 495.2942057567965, 561.5142901390299, 34574.481382779246, 34958.825205873996, 39974.80012599937, 43107.78446107773, 43275.350869736976, 44019.33970990438, 44541.77729111358, 44543.331850022274, 44637.77681111598, 44656.0, 8500.44747091437, 9609.279304052206, 9887.258455615822, 11482.253653512536, 11913.702157446047, 9811.50942452878, 9876.802463950717, 11763.235389699685, 11820.881791182108, 11849.0, 11651.300921944669, 11752.52989880408, 11802.0, 11843.0, 11859.347735874524, 11771.764564708657, 11796.823047654292, 11820.70448238788, 11821.34982575958, 11877.406129693481, 11480.77038459226, 11521.308721476655, 11565.306081635128, 11569.88430115705, 11603.245789023771, 38990.44038238467, 39044.414333784734, 39431.40852887209, 39492.60507394918, 39525.20949581021, 4048.2308361411533, 4104.240715467648, 4120.196561670476, 4160.2303573838935, 4295.3771703102875, 23198.65202021971, 23606.763932360627, 29910.85044574785, 30204.0, 30812.537811932838, 3249.4639523425103, 3305.7024867761775, 3331.4336562784383, 3336.7831090979007, 3385.661433856619, 434180.8290958525, 507457.0, 509092.0, 511291.88708112814, 511552.0, 60409.0, 60437.79124417487, 60571.0, 60712.48218794477, 60715.69642151761, 62172.0, 62415.6879215601, 62779.686101570114, 63001.0, 63088.05367919485, 16306.10316432584, 16971.406000789913, 16981.915090424714, 16988.57528561771, 17037.914810425867, 12071.939640301918, 12340.382980850922, 12958.22250664963, 13099.803502947463, 13148.211107333591, 16311.836881631032, 17215.483535493957, 18448.907755461136, 18514.722279165824, 18574.721379179322, 6675.46596272297, 7915.208479152154, 7967.127724984624, 8010.839783204303, 8022.83954320922, 821.4414198345063, 853.568947681417, 864.204931463043, 871.9346049046347, 896.6906417286026, 31205.531917021264, 31246.843765781025, 31267.06198814039, 31589.684103159136, 31866.36267274688, 831.6174559702483, 1081.5129197353638, 1091.6179337231906, 1126.583164229224, 1215.4166000319897, 16257.674846503003, 16267.51197464078, 16309.347626095061, 16458.93016953894, 16590.917045414695, 562270.0, 637447.0, 653013.0, 654174.0, 654629.0, 62019.689901550206, 62551.06173407403, 62780.686096569225, 62859.371406286264, 62958.370416296166, 1417.3692706745605, 1456.3155317000992, 1465.9413623455155, 1477.0694462488564, 1487.7098965701584, 28674.713252867205, 30980.690193098682, 31101.377972440878, 31489.0, 31592.842035789676, 51345.973080537435, 51591.226131607305, 51634.74182629063, 51641.225381618555, 51658.0, 506842.0, 517342.0, 519087.0, 519094.0, 520428.0, 25444.236672899842, 26166.4766704661, 26228.344291392867, 26914.730852692006, 27656.585151222345, 43569.475068372456, 43641.0, 44837.77581112074, 45199.77400112979, 45546.772266138454, 36861.44707829438, 37131.44302835406, 37234.813825930694, 37593.0, 38214.426783597715, 36773.44839827458, 36951.26097477982, 37675.05812354713, 38137.427938581495, 38158.85523434301, 37303.44044839275, 37541.812290939466, 37606.30771615229, 38296.42555361616, 39029.414558782206, 30171.0, 33703.0, 33868.98393048212, 34039.149021274665, 34255.0, 24736.381590460383, 25030.123945661813, 25465.49069018572, 25502.23493295204, 25698.486030279662], "value": 20457.535436905728, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.79196961747, 403.2479425870752, 407.547622139425, 414.45292214277157, 441.0583404431538, 21101.0, 21492.605892312222, 21957.01193446295, 22163.556728865413, 22453.0, 22303.996320165592, 22310.442238944033, 22406.439839004033, 22793.658095128587, 22960.311190664266, 5977.2229610150725, 6236.345183755709, 6285.120083188334, 6285.402886725754, 6306.117143599877, 5966.552508561854, 6108.69456527174, 6152.415520525543, 6152.630842149485, 6170.1670274513035, 6029.065494848305, 6063.545234107438, 6096.2074930259005, 6146.723397447103, 6163.691815409234, 5904.1734157218025, 6114.449699527042, 6121.969390153065, 6155.753769849201, 6156.692165391735, 4586.3808385867815, 5275.103232450492, 5355.062863998784, 5391.29913111293, 5455.1544510600925, 26864.865675671495, 26865.328366790793, 27027.729722702916, 27055.729442705713, 27308.0, 2789.623400840881, 2790.2884764384953, 2795.1335086123368, 2797.9160625181275, 2853.671827739816, 12182.573609923698, 14190.43238270468, 16041.839581604152, 16197.757033644504, 16631.002139871525, 1551.4414810668263, 1889.7165425186195, 1892.3187652445108, 1893.8011508791517, 1897.3074827687933, 122139.77860221373, 124928.75071249262, 125258.0, 125817.0, 128876.7112328874, 9656.32405731592, 12010.759784804255, 12011.0, 12043.337616431181, 12274.815877761841, 15358.155301458304, 15524.767128493082, 15535.456259031105, 15540.222988850512, 15558.299876505358, 9020.503872287087, 9259.305552083486, 9297.070292970784, 9299.34904556688, 9352.719418417459, 9125.81748365029, 9145.131212534798, 9155.954220228856, 9317.673881414117, 9320.06799320076, 7599.772006839804, 7708.03649543807, 7778.727744528915, 7779.649915753805, 7798.142204357516, 1975.604879024172, 1993.4318719165258, 2004.3185316992292, 2006.809353111452, 2008.1666108565073, 1008.7441135785994, 1041.682286902502, 1071.4428497181423, 1083.951222195003, 1105.8728246251624, 19964.42575495645, 22839.543209136056, 22949.770502294763, 23001.88499057494, 23075.538489230123, 1095.315427857589, 1099.9945000274947, 1104.1994553948484, 1132.739469921906, 1139.7663478986954, 11856.288622682494, 11959.342236176919, 12044.759104817855, 12065.638030859089, 12069.698257543632, 754449.0, 756111.0, 757235.0, 776597.0, 779764.0, 81628.0, 103779.0, 111970.44014779874, 133513.5511185757, 138863.30568347295, 1072.6299426697776, 1077.9299345542515, 1108.4346983038547, 1111.7109551516503, 1112.3214838948236, 17217.82782172162, 20647.27734529284, 21706.34880953574, 22011.559768804214, 22149.889250553642, 56900.71549642225, 57699.0, 58065.96769113061, 59522.40477595168, 59579.404205957384, 60858.08712869222, 82217.0, 82534.17465825265, 82878.17121828704, 82954.0, 8258.917410826056, 8272.131426200162, 8399.328053755808, 8529.786755331166, 8558.272546833588, 9729.562169702238, 12641.683957901127, 13081.280529570964, 13512.0, 13521.256330901502, 9056.501892395981, 11344.773104538028, 11724.648260551856, 11798.764024719287, 11831.17181797266, 9709.0, 11075.446227688744, 11175.385353805294, 11277.0, 11729.941350293195, 11187.664370068911, 11460.656180314603, 11550.711232218926, 11879.406029698308, 12033.819492707791, 8139.267465928095, 8249.236043825045, 8520.446170998865, 8626.827463450822, 8663.350248731496, 11290.548378064785, 11595.014423774073, 11604.6518604442, 11624.82562761576, 11631.185816992729], "value": 10380.265160902638, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1414.151509094544, 1470.1987416857812, 1485.1979930837347, 1488.8436714145007, 1489.4637930345077, 7779.961100194491, 8008.799780005488, 8261.545614991173, 8707.651693932243, 8709.390342676015, 8123.228293312126, 8419.368547358958, 8441.746747597566, 8525.062243153248, 8541.231289183972, 2369.3721163891582, 2370.146747171017, 2411.7829395354415, 2411.8914648840846, 2417.528581926525, 2199.967000494994, 2259.836184365053, 2372.264597974625, 2421.079989603948, 2426.8543887366727, 2395.9161429349974, 2404.7955923746417, 2410.8794560272004, 2426.5996110641813, 2437.8049756019564, 2379.238643634038, 2385.689860318165, 2395.772401621843, 2397.4006498375406, 2399.928002159938, 2355.729091154505, 2372.644103384489, 2427.647991041307, 2433.4281443860677, 2437.9756202437925, 8537.871931921027, 8610.956945215297, 8627.223549880478, 8638.65445382184, 8663.653453861838, 5184.274201611777, 5311.362636483608, 5327.653702509363, 5342.706151161685, 5343.091674415358, 3531.2584357285, 3654.488371627974, 3655.4151335786414, 3677.9264414711556, 3726.4037753959506, 1073.2755390111722, 1225.5587988324196, 1258.7860063789178, 1292.8448586169625, 1318.6571491412205, 11028.28316159455, 11032.724181895437, 11034.448277586089, 11059.0, 11094.00153986145, 4680.828325909196, 4725.676810493067, 4794.017226468565, 4817.494163112893, 4854.466008739036, 9490.525473726286, 9635.855462168072, 9671.951640241754, 9681.467519286507, 9698.0, 5343.866403339947, 5363.865903352369, 5366.543843773323, 5375.274337964324, 5382.111951527954, 4460.888477788081, 4510.977445112753, 4784.114938736345, 4908.165611845995, 4962.652614317035, 3516.191276006533, 3917.3732202847978, 3939.92120157601, 4051.9797401012806, 4089.1003979124544, 1092.8032954068185, 1212.496813822254, 1321.4119716725966, 1324.5165514587106, 1334.4061892457958, 749.5615065186818, 794.1700922535964, 810.9675612975415, 817.4808996287281, 817.7301490508119, 4692.0, 5650.463205995424, 5716.42835716425, 5748.770049198069, 5790.478856902899, 509.96477151382754, 570.287141073658, 593.6912805341285, 599.7960693364278, 604.9848753781102, 3634.636536346397, 3735.9252814943547, 3736.252749450121, 3743.9625603744157, 3856.2094770571966, 9960.601575937026, 9978.251631127549, 10127.088562029452, 10273.589056437808, 10286.948565257126, 9288.931772846074, 9452.52737363129, 10034.397936123856, 10244.59021639141, 10244.692659220234, 6370.617762934239, 7974.122846486998, 8036.919630803734, 8045.83908321842, 8048.396370272253, 5471.425500322409, 5528.9447105530035, 5615.747291371979, 5676.914846277392, 5695.943040569541, 9123.087691230823, 9323.766905827137, 9423.528823558932, 9436.150746432853, 9501.097395747393, 9115.544222788967, 9421.811563768822, 9432.717018489457, 9486.857697134687, 9508.429494230368, 6813.659317034029, 6863.519553631198, 6990.545614535039, 7300.0, 7475.327220549959, 7951.642176102205, 8204.220599043081, 8209.71266005687, 8214.0, 8215.0, 7292.197858235696, 7341.4860959732305, 7391.778246652609, 7477.364424024019, 7503.362214211853, 7185.676644551112, 7233.276672332725, 7260.600666963368, 7382.151052629017, 7388.076490438695, 6435.774747883802, 6563.0, 6569.835754105994, 6580.21037475506, 6585.868282634417, 4436.689431739876, 4456.554344565518, 4509.391232183744, 4516.0, 4561.452625684939, 4169.749815011109, 4999.550040496373, 5005.374328208974, 5141.485851414828, 5256.947430525799], "value": 4417.492438710312, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.5380528145101, 373.21065945525214, 380.53004539393845, 385.1238432565914, 387.2931899283806, 5230.895382092356, 5317.2821669074665, 5420.647657902234, 5434.130539113743, 5524.116141417374, 4258.0, 5102.515261050204, 5404.7838086476495, 5457.617966742329, 5475.288212532365, 4292.896480724454, 4321.827126914927, 4325.048489332342, 4340.958170039199, 4349.630281426083, 3495.685388315051, 4292.14157168566, 4309.698321117523, 4321.178975994567, 4358.1283743251315, 3648.726345524083, 3713.6472035156617, 4275.551067137937, 4278.422412974255, 4286.0, 3809.561900381464, 4295.355696645497, 4300.440942677447, 4308.676849236304, 4319.460067491564, 3807.9619203807883, 3808.485854409647, 3812.3137835189655, 3816.271365973342, 3829.3872980323295, 4250.702450828443, 5089.312942752718, 5347.454663535963, 5382.416440108731, 5421.698792289862, 3960.920781584352, 4655.906881862377, 4953.777080031407, 4964.932539504024, 5008.924866127011, 4851.442084160331, 5152.072626927152, 5701.572382071361, 5837.387074357174, 5873.941260587382, 3379.0369744622662, 3496.685298323138, 3970.602939706035, 3971.6425521703186, 4002.4996875390575, 5512.614117011811, 5954.255718035246, 6116.602420842631, 6127.947137708873, 6159.38406159385, 3546.113471632092, 3641.851307728453, 3958.782266975344, 4042.595740425934, 4046.716729828943, 3894.0240225396556, 4062.898427539276, 4083.387491876242, 4090.6318431341324, 4116.670666346685, 4102.118044620421, 4537.4328208973875, 4704.129735998851, 4762.166620841339, 4763.071201115818, 5537.252470916455, 5775.393583673737, 5852.531797456193, 5879.52963762898, 5921.572155416345, 1237.300924977385, 1274.413769665964, 1291.2381694799926, 1317.848447428558, 1334.6129622409587, 866.4368160695599, 875.9094926816027, 891.9643214271487, 905.3798148268457, 906.4923642760075, 4213.9578604214175, 4427.867163985085, 4815.95184048155, 4918.884879501848, 5178.559822415137, 4933.703977761345, 5359.713668719479, 5753.683547404933, 5810.82567522975, 5832.766689332379, 4352.695311328177, 4769.855234743636, 4873.122837889214, 5183.352080989876, 5202.930271140046, 2936.9118926432243, 2988.865501052458, 3025.909222723322, 3030.817980987417, 3044.604201453797, 5284.248183789552, 5318.680879147263, 5877.706114694248, 5988.880222395528, 6012.939870601325, 5194.830808068325, 5471.425500322488, 5875.941240587625, 5928.436798504135, 5992.471919660458, 5183.352080989876, 5619.353774316006, 5648.774049038075, 6017.398260173947, 6046.637201767908, 4465.866024019155, 5784.942150578609, 5791.942080579141, 5834.474897259267, 5844.824655260349, 5307.081105392096, 5621.213030175697, 5625.943740562706, 5731.799387021434, 5795.565332599987, 4625.468071171858, 5082.822101226439, 5924.940750592439, 5935.584509084495, 5974.430994672332, 3246.2632491616873, 3254.560634314337, 3560.181158333546, 3570.2145527983803, 3759.4398324694944, 5079.568236699774, 5524.309461317335, 5682.261306030189, 6028.909566356596, 6053.334133245427, 5337.6797392156595, 5447.891042179213, 5492.258545096361, 5709.172170035238, 5811.912821307599, 4803.807847686193, 5262.89474210506, 5916.201312822714, 5918.644881307136, 5930.7924222652, 4939.382577177853, 5098.617603679665, 5383.919241211307, 5766.509846663081, 5806.332271788629, 5781.8029148924825, 5845.0, 5845.970770146122, 5896.027155519291, 5934.376890426615, 4746.406699162605, 5015.824446144367, 5293.814716484904, 5646.774129034792, 5730.169125476866], "value": 4171.252850126698, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [625.5371025441175, 738.5937734246163, 768.6579472134899, 786.4376970466116, 895.5164211325884, 12476.001919846412, 15048.774268385969, 16133.19334033298, 17087.23107460164, 17992.640147197082, 13587.93206033971, 17088.914555427207, 17122.82877171228, 17263.48209553712, 17442.56393590161, 879.4239772948729, 904.0688091266006, 904.9366544341899, 909.2952961454893, 918.0131358789329, 714.1430283659603, 899.2850683706469, 900.1943260781629, 901.9774505637379, 902.7201567514059, 763.6601712238056, 908.1554154636186, 933.4912472702359, 942.7360339105062, 942.924566034719, 925.8148370325928, 929.419113054341, 929.4794914847639, 933.3839665820526, 934.53273363318, 696.954697944632, 699.0178798787712, 699.9020137180748, 700.0619170311755, 705.8270723672733, 15617.765733514007, 15644.374225030984, 15951.282192301374, 16015.119168445732, 16056.518304450885, 14432.278386080654, 14846.331915063847, 14866.628334291621, 14886.925565372212, 14895.0, 11750.706232344175, 11875.0, 11884.286942783374, 11889.643310700692, 11920.46357913896, 3734.3838266685966, 3819.732618716691, 3820.4842346283176, 3853.402722578005, 3976.7415118017525, 116162.41918790436, 118035.40982295033, 119374.40312798467, 119572.40213798963, 119750.4012479932, 10824.621138260281, 10825.296355736851, 10861.728456788644, 10965.287256328313, 11064.834027489595, 23224.3032709019, 23720.762792371854, 23764.16825411137, 23780.405489862547, 23801.523969520513, 7917.2874441300555, 7977.2022797719765, 7977.800554986172, 7997.480163789335, 8035.839283214303, 4070.776107314126, 4222.619964203236, 4374.934375984363, 4469.4636643602325, 4608.377868987711, 3711.257748450321, 3718.9442158367647, 3745.194783121642, 3790.166163444038, 3815.1606646537716, 575.493565662223, 685.4858855858106, 685.67773146621, 696.6342670098138, 706.4313227851526, 546.5136028934172, 686.5743239191634, 712.590260600157, 727.1637616740685, 734.0657367835792, 9265.583048762823, 9513.66702165421, 9955.800883982425, 10122.190224782, 10129.848052279222, 638.1225814505055, 671.8454755406186, 679.2562144451817, 725.3435640745134, 742.1835980421494, 4500.684952053325, 4706.694064885772, 4726.291056341576, 5047.293378926953, 5066.82266120684, 97766.0, 112187.0, 112241.0, 115751.84248157579, 117530.0, 99332.0, 102237.0, 104698.0, 104928.47535762425, 105206.47396762967, 89086.0, 89186.0, 90592.54703726439, 91995.08004919998, 92192.53903730438, 6988.8951665724035, 9459.621615135318, 9671.564779584794, 9759.512024398893, 9781.559829807533, 47137.52862471469, 52053.7397313011, 54432.45567544274, 54853.72573137269, 56011.43988560062, 59378.92173773897, 68263.65868170628, 69280.30719692739, 71633.64183179259, 73852.63073684597, 11649.534018639159, 11681.240719353214, 11862.881371186522, 12208.0, 12246.816297755362, 12528.320677206522, 12567.308798016196, 12661.113722039368, 12993.0, 13144.80282795778, 11752.177347585586, 11850.992665623518, 11943.343116128348, 12179.39103044862, 12383.504659813507, 9688.467134307399, 11757.0, 12079.39603019863, 12314.261144331369, 12440.37798110109, 10954.0, 11177.882211778757, 12031.819522706992, 12339.814902776645, 12430.751384972431, 9876.851847222153, 10221.591136354462, 10250.948745256226, 10350.948245258725, 10620.415877126832, 7054.329838665319, 8920.73237802867, 9959.0, 9965.0, 9984.900150998397], "value": 7583.038840823531, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.7999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 117.0, 118.0, 118.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 111.0, 143.0, 113.0, 113.0, 112.0, 110.0, 130.0, 116.0], "value": 124.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 196.0, 196.0, 194.0, 201.0, 196.0, 198.0, 198.0, 199.0, 192.0], "value": 196.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 64.0, 91.0, 85.0, 59.0, 60.0, 59.0, 71.0, 60.0], "value": 68.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 82.0, 74.0, 71.0, 71.0, 81.0, 69.0, 71.0, 81.0], "value": 75.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 89.0, 90.0, 89.0, 83.0, 137.0, 88.0, 103.0, 86.0], "value": 94.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 94.0, 106.0, 155.0, 110.0, 153.0, 109.0, 154.0, 133.0, 150.0], "value": 126.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 101.0, 98.0, 75.0, 76.0, 143.0, 149.0, 144.0, 92.0, 92.0], "value": 107.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 65.0, 59.0, 61.0, 62.0, 62.0, 62.0, 73.0, 79.0, 61.0], "value": 64.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 68.0, 74.0, 69.0, 67.0, 74.0, 68.0, 71.0, 73.0, 60.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 73.0, 90.0, 73.0, 89.0, 69.0, 88.0, 103.0, 70.0, 94.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 140.0, 140.0, 146.0, 140.0, 147.0, 155.0, 162.0, 180.0, 156.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [239.0, 203.0, 164.0, 224.0, 163.0, 209.0, 230.0, 208.0, 167.0, 166.0], "value": 197.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 64.0, 64.0, 68.0, 66.0, 64.0, 63.0, 75.0], "value": 65.8, "unit": "score"}]}]} 22:40:01 INFO - Return code: 0 22:40:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:40:01 ERROR - # TBPL SUCCESS # 22:40:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:40:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:40:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:40:01 INFO - 2017-03-16 22:40:01.197 screenresolution[1659:10259] starting screenresolution argv=screenresolution get 22:40:01 INFO - 2017-03-16 22:40:01.200 screenresolution[1659:10259] Display 0: 1600x1200x32@60 22:40:01 INFO - 2017-03-16 22:40:01.206 screenresolution[1660:10262] starting screenresolution argv=screenresolution list 22:40:01 INFO - Available Modes on Display 0 22:40:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:40:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:40:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:40:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:40:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:40:01 INFO - Intel Iris: 22:40:01 INFO - Chipset Model: Intel Iris 22:40:01 INFO - Type: GPU 22:40:01 INFO - Bus: Built-In 22:40:01 INFO - VRAM (Dynamic, Max): 1536 MB 22:40:01 INFO - Vendor: Intel (0x8086) 22:40:01 INFO - Device ID: 0x0a2e 22:40:01 INFO - Revision ID: 0x0009 22:40:01 INFO - Displays: 22:40:01 INFO - Display: 22:40:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:40:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:40:01 INFO - Display Serial Number: 1600x1200 60 22:40:01 INFO - Main Display: Yes 22:40:01 INFO - Mirror: Off 22:40:01 INFO - Online: Yes 22:40:01 INFO - Rotation: Supported 22:40:01 INFO - Return code: 0 22:40:01 INFO - Running post-action listener: _resource_record_post_action 22:40:01 INFO - [mozharness: 2017-03-17 05:40:01.440129Z] Finished run-tests step (success) 22:40:01 INFO - Running post-run listener: _resource_record_post_run 22:40:01 INFO - Total resource usage - Wall time: 1190s; CPU: 54.0%; Read bytes: 40523776; Write bytes: 424020992; Read time: 391; Write time: 1597 22:40:01 INFO - TinderboxPrint: CPU usage
54.2% 22:40:01 INFO - TinderboxPrint: I/O read bytes / time
40,523,776 / 391 22:40:01 INFO - TinderboxPrint: I/O write bytes / time
424,020,992 / 1,597 22:40:01 INFO - TinderboxPrint: CPU idle
2,180.6 (45.8%) 22:40:01 INFO - TinderboxPrint: CPU system
956.7 (20.1%) 22:40:01 INFO - TinderboxPrint: CPU user
1,624.3 (34.1%) 22:40:01 INFO - TinderboxPrint: Swap in / out
162,979,840 / 0 22:40:01 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 109928960; Write bytes: 102248448; Read time: 9933; Write time: 219 22:40:01 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 39593984; Write bytes: 299955200; Read time: 377; Write time: 1300 22:40:01 INFO - Running post-run listener: _upload_blobber_files 22:40:01 INFO - Blob upload gear active. 22:40:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:40:01 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 22:40:01 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'] 22:40:01 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 22:40:02 INFO - (blobuploader) - INFO - Open directory for files ... 22:40:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:40:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:40:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:40:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:40:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:40:03 INFO - (blobuploader) - INFO - Done attempting. 22:40:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:40:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:40:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:40:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:40:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:40:04 INFO - (blobuploader) - INFO - Done attempting. 22:40:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:40:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:40:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:40:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:40:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:40:06 INFO - (blobuploader) - INFO - Done attempting. 22:40:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:40:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:40:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:40:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:40:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:40:09 INFO - (blobuploader) - INFO - Done attempting. 22:40:09 INFO - (blobuploader) - INFO - Iteration through files over. 22:40:09 INFO - Return code: 0 22:40:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:40:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:40:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ae6cf71203d17f9abbd96904e83b74b7abbb3931f97d98929b6765226cae178a55ad4df28534ee58403e028cf13499fcd480b0de11ba02b1c851ec45d1fdd6d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/714e99d84926bfc28f27e82895d9c634b3e3478a3bd57d26343becea743172485e1ee0bd0b1a9827463225c4e0888cdae084dfd11f6842efe9ab5e3480264e76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6593e8d8cc0b0d819efb06899153ab9bb8d70704c315190aec6e80f2995935500de91c2ac4be9895eebf0eeed60d81e231c846b83d70f9752a869f8c99c13688", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/49944b87c7b018f7fb5ee1432fa4bdda9bd442be023ce274cfaa213d7c4aae75b83214ec9330b739f492c304407c7f5c1ed818dca66bd7a2b9a850a17cd3a02c"} 22:40:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:40:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:40:09 INFO - Contents: 22:40:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ae6cf71203d17f9abbd96904e83b74b7abbb3931f97d98929b6765226cae178a55ad4df28534ee58403e028cf13499fcd480b0de11ba02b1c851ec45d1fdd6d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/714e99d84926bfc28f27e82895d9c634b3e3478a3bd57d26343becea743172485e1ee0bd0b1a9827463225c4e0888cdae084dfd11f6842efe9ab5e3480264e76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6593e8d8cc0b0d819efb06899153ab9bb8d70704c315190aec6e80f2995935500de91c2ac4be9895eebf0eeed60d81e231c846b83d70f9752a869f8c99c13688", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/49944b87c7b018f7fb5ee1432fa4bdda9bd442be023ce274cfaa213d7c4aae75b83214ec9330b739f492c304407c7f5c1ed818dca66bd7a2b9a850a17cd3a02c"} 22:40:09 INFO - Running post-run listener: copy_logs_to_upload_dir 22:40:09 INFO - Copying logs to upload dir... 22:40:09 INFO - mkdir: /builds/slave/test/build/upload/logs 22:40:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.303170 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-16 22:40:09.041123) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-16 22:40:09.045265) ========= 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.OhrXOb8r1c/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.7rEbpN27Jw/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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/2ae6cf71203d17f9abbd96904e83b74b7abbb3931f97d98929b6765226cae178a55ad4df28534ee58403e028cf13499fcd480b0de11ba02b1c851ec45d1fdd6d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/714e99d84926bfc28f27e82895d9c634b3e3478a3bd57d26343becea743172485e1ee0bd0b1a9827463225c4e0888cdae084dfd11f6842efe9ab5e3480264e76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6593e8d8cc0b0d819efb06899153ab9bb8d70704c315190aec6e80f2995935500de91c2ac4be9895eebf0eeed60d81e231c846b83d70f9752a869f8c99c13688", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/49944b87c7b018f7fb5ee1432fa4bdda9bd442be023ce274cfaa213d7c4aae75b83214ec9330b739f492c304407c7f5c1ed818dca66bd7a2b9a850a17cd3a02c"} build_url:https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011446 build_url: 'https://queue.taskcluster.net/v1/task/Hx9WZ7GfRkKqhME7lMDNmw/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ae6cf71203d17f9abbd96904e83b74b7abbb3931f97d98929b6765226cae178a55ad4df28534ee58403e028cf13499fcd480b0de11ba02b1c851ec45d1fdd6d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/714e99d84926bfc28f27e82895d9c634b3e3478a3bd57d26343becea743172485e1ee0bd0b1a9827463225c4e0888cdae084dfd11f6842efe9ab5e3480264e76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6593e8d8cc0b0d819efb06899153ab9bb8d70704c315190aec6e80f2995935500de91c2ac4be9895eebf0eeed60d81e231c846b83d70f9752a869f8c99c13688", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/49944b87c7b018f7fb5ee1432fa4bdda9bd442be023ce274cfaa213d7c4aae75b83214ec9330b739f492c304407c7f5c1ed818dca66bd7a2b9a850a17cd3a02c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-16 22:40:09.080256) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 22:40:09.080691) ========= 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.OhrXOb8r1c/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.7rEbpN27Jw/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.005152 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 22:40:09.129547) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-16 22:40:09.129886) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-16 22:40:09.130259) ========= ========= Total master_lag: 0.20 =========