builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0219 starttime: 1488205609.67 results: success (0) buildid: 20170227050709 builduid: 30748400a75f427a9b30461f1aed3e44 revision: 43f52c13c08d29cc2b13c3e650d4412017dc74bd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-27 06:26:49.680201) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-27 06:26:49.680889) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-27 06:26:49.681219) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-27 06:26:49.709723) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 06:26:49.710196) ========= 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.sk7xjHVoaS/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.2BbX4nGbcE/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-27 06:26:49-- 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% 71.3M=0s 2017-02-27 06:26:50 (71.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.600725 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 06:26:50.335573) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 06:26:50.335882) ========= 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.sk7xjHVoaS/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.2BbX4nGbcE/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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.025860 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 06:26:50.385003) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 06:26:50.385382) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 43f52c13c08d29cc2b13c3e650d4412017dc74bd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 43f52c13c08d29cc2b13c3e650d4412017dc74bd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sk7xjHVoaS/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.2BbX4nGbcE/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-27 06:26:50,491 truncating revision to first 12 chars 2017-02-27 06:26:50,491 Setting DEBUG logging. 2017-02-27 06:26:50,491 attempt 1/10 2017-02-27 06:26:50,491 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/43f52c13c08d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-27 06:26:51,142 unpacking tar archive at: mozilla-central-43f52c13c08d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.916000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 06:26:51.324842) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-27 06:26:51.325152) ========= script_repo_revision: 43f52c13c08d29cc2b13c3e650d4412017dc74bd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-27 06:26:51.325553) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-27 06:26:51.325819) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-27 06:26:51.349261) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-02-27 06:26:51.349614) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sk7xjHVoaS/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.2BbX4nGbcE/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 06:26:51 INFO - MultiFileLogger online at 20170227 06:26:51 in /builds/slave/test 06:26:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:26:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:26:51 INFO - {'append_to_log': False, 06:26:51 INFO - 'base_work_dir': '/builds/slave/test', 06:26:51 INFO - 'blob_upload_branch': 'Firefox', 06:26:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:26:51 INFO - 'branch': 'Firefox', 06:26:51 INFO - 'buildbot_json_path': 'buildprops.json', 06:26:51 INFO - 'config_files': ('talos/mac_config.py',), 06:26:51 INFO - 'default_actions': ('clobber', 06:26:51 INFO - 'read-buildbot-config', 06:26:51 INFO - 'download-and-extract', 06:26:51 INFO - 'populate-webroot', 06:26:51 INFO - 'create-virtualenv', 06:26:51 INFO - 'install', 06:26:51 INFO - 'run-tests'), 06:26:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:26:51 INFO - 'download_minidump_stackwalk': True, 06:26:51 INFO - 'download_symbols': 'ondemand', 06:26:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:26:51 INFO - 'tooltool.py': '/tools/tooltool.py', 06:26:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:26:51 INFO - '/tools/misc-python/virtualenv.py')}, 06:26:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:26:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:26:51 INFO - 'gecko_profile': False, 06:26:51 INFO - 'gecko_profile_interval': 0, 06:26:51 INFO - 'installer_path': 'installer.exe', 06:26:51 INFO - 'log_level': 'info', 06:26:51 INFO - 'log_name': 'talos', 06:26:51 INFO - 'log_to_console': True, 06:26:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:26:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:26:51 INFO - 'opt_config_files': (), 06:26:51 INFO - 'pip_index': False, 06:26:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:26:51 INFO - 'cmd': ('bash', 06:26:51 INFO - '-c', 06:26:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:26:51 INFO - 'enabled': True, 06:26:51 INFO - 'halt_on_failure': False, 06:26:51 INFO - 'name': 'check_screen_resolution'},), 06:26:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:26:51 INFO - 'cmd': ('bash', 06:26:51 INFO - '-c', 06:26:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:26:51 INFO - 'enabled': True, 06:26:51 INFO - 'halt_on_failure': False, 06:26:51 INFO - 'name': 'check_screen_resolution'},), 06:26:51 INFO - 'run_cmd_checks_enabled': True, 06:26:51 INFO - 'suite': 'dromaeojs-e10s', 06:26:51 INFO - 'system_bits': '32', 06:26:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:26:51 INFO - 'title': 't-yosemite-r7-0219', 06:26:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:26:51 INFO - 'use_talos_json': True, 06:26:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:26:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:26:51 INFO - 'work_dir': 'build'} 06:26:51 INFO - [mozharness: 2017-02-27 14:26:51.562700Z] Running clobber step. 06:26:51 INFO - Running pre-action listener: _resource_record_pre_action 06:26:51 INFO - Running main action method: clobber 06:26:51 INFO - rmtree: /builds/slave/test/build 06:26:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:26:54 INFO - Running post-action listener: _resource_record_post_action 06:26:54 INFO - [mozharness: 2017-02-27 14:26:54.015805Z] Finished clobber step (success) 06:26:54 INFO - [mozharness: 2017-02-27 14:26:54.016046Z] Running read-buildbot-config step. 06:26:54 INFO - Running pre-action listener: _resource_record_pre_action 06:26:54 INFO - Running main action method: read_buildbot_config 06:26:54 INFO - Using buildbot properties: 06:26:54 INFO - { 06:26:54 INFO - "project": "", 06:26:54 INFO - "product": "firefox", 06:26:54 INFO - "script_repo_revision": "production", 06:26:54 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:26:54 INFO - "repository": "", 06:26:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:26:54 INFO - "buildid": "20170227050709", 06:26:54 INFO - "pgo_build": "False", 06:26:54 INFO - "basedir": "/builds/slave/test", 06:26:54 INFO - "buildnumber": 102, 06:26:54 INFO - "slavename": "t-yosemite-r7-0219", 06:26:54 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 06:26:54 INFO - "platform": "yosemite_r7", 06:26:54 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:26:54 INFO - "branch": "mozilla-central", 06:26:54 INFO - "repo_path": "mozilla-central", 06:26:54 INFO - "revision": "43f52c13c08d29cc2b13c3e650d4412017dc74bd", 06:26:54 INFO - "stage_platform": "macosx64", 06:26:54 INFO - "builduid": "30748400a75f427a9b30461f1aed3e44", 06:26:54 INFO - "slavebuilddir": "test" 06:26:54 INFO - } 06:26:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 06:26:54 INFO - Running post-action listener: _resource_record_post_action 06:26:54 INFO - [mozharness: 2017-02-27 14:26:54.021122Z] Finished read-buildbot-config step (success) 06:26:54 INFO - [mozharness: 2017-02-27 14:26:54.021334Z] Running download-and-extract step. 06:26:54 INFO - Running pre-action listener: _resource_record_pre_action 06:26:54 INFO - Running main action method: download_and_extract 06:26:54 INFO - mkdir: /builds/slave/test/build/tests 06:26: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')]} 06:26:54 INFO - trying https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 06:26:54 INFO - Downloading https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 06:26:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 06:26:55 INFO - Downloaded 1199 bytes. 06:26:55 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 06:26:55 INFO - Using the following test package requirements: 06:26:55 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 06:26:55 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:26:55 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 06:26:55 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:26:55 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 06:26:55 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:26:55 INFO - u'jsshell-mac.zip'], 06:26:55 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:26:55 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 06:26:55 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 06:26:55 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:26:55 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 06:26:55 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:26:55 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 06:26:55 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:26:55 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 06:26:55 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:26:55 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 06:26:55 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:26:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 06:26:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:26:55 INFO - Fetch https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 06:26:56 INFO - Expected file size: 51744792 06:26:56 INFO - Obtained file size: 51744792 06:27:13 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:27:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 06:27:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:27:13 INFO - Fetch https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 06:27:15 INFO - Expected file size: 51744792 06:27:15 INFO - Obtained file size: 51744792 06:27:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 06:27:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:27:29 INFO - Fetch https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 06:27:30 INFO - Expected file size: 14404421 06:27:30 INFO - Obtained file size: 14404421 06:27:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:27:31 INFO - trying https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:27:31 INFO - Downloading https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 06:27:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 06:27:33 INFO - Downloaded 74979794 bytes. 06:27:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:27:33 INFO - mkdir: /builds/slave/test/properties 06:27:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:27:33 INFO - Writing to file /builds/slave/test/properties/build_url 06:27:33 INFO - Contents: 06:27:33 INFO - build_url:https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:27:34 INFO - Running post-action listener: _resource_record_post_action 06:27:34 INFO - Running post-action listener: set_extra_try_arguments 06:27:34 INFO - [mozharness: 2017-02-27 14:27:34.151688Z] Finished download-and-extract step (success) 06:27:34 INFO - [mozharness: 2017-02-27 14:27:34.151825Z] Running populate-webroot step. 06:27:34 INFO - Running pre-action listener: _resource_record_pre_action 06:27:34 INFO - Running main action method: populate_webroot 06:27:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:27:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:27:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:27:34 INFO - '--tppagecycles', 06:27:34 INFO - '7'], 06:27:34 INFO - 'tests': ['tsvgm']}}, 06:27:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:27:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:27:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:27:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:27:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:27:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:27:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:27:34 INFO - 'talos_options': ['--disable-e10s'], 06:27:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:27:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:27:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:27:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:27:34 INFO - 'talos_options': ['--disable-e10s'], 06:27:34 INFO - 'tests': ['damp', 'tps']}, 06:27:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:27:34 INFO - 'tests': ['damp', 'tps']}, 06:27:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:27:34 INFO - 'tests': ['dromaeo_dom']}, 06:27:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:27:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:27:34 INFO - 'tests': ['basic_compositor_video']}, 06:27:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:27:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:27:34 INFO - 'tests': ['a11yr', 06:27:34 INFO - 'ts_paint', 06:27:34 INFO - 'tpaint', 06:27:34 INFO - 'sessionrestore', 06:27:34 INFO - 'sessionrestore_no_auto_restore', 06:27:34 INFO - 'tabpaint']}, 06:27:34 INFO - 'other-e10s': {'tests': ['a11yr', 06:27:34 INFO - 'ts_paint', 06:27:34 INFO - 'tpaint', 06:27:34 INFO - 'sessionrestore', 06:27:34 INFO - 'sessionrestore_no_auto_restore', 06:27:34 INFO - 'tabpaint']}, 06:27:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:27:34 INFO - 'ts_paint', 06:27:34 INFO - 'tpaint', 06:27:34 INFO - 'sessionrestore', 06:27:34 INFO - 'sessionrestore_no_auto_restore', 06:27:34 INFO - 'tabpaint']}, 06:27:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:27:34 INFO - 'ts_paint', 06:27:34 INFO - 'tpaint', 06:27:34 INFO - 'sessionrestore', 06:27:34 INFO - 'sessionrestore_no_auto_restore', 06:27:34 INFO - 'tabpaint']}, 06:27:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:27:34 INFO - 'tests': ['a11yr', 06:27:34 INFO - 'ts_paint', 06:27:34 INFO - 'tpaint', 06:27:34 INFO - 'sessionrestore', 06:27:34 INFO - 'sessionrestore_no_auto_restore', 06:27:34 INFO - 'tabpaint']}, 06:27:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:27:34 INFO - 'tests': ['a11yr', 06:27:34 INFO - 'ts_paint', 06:27:34 INFO - 'tpaint', 06:27:34 INFO - 'sessionrestore', 06:27:34 INFO - 'sessionrestore_no_auto_restore', 06:27:34 INFO - 'tabpaint']}, 06:27:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:27:34 INFO - 'tests': ['tsvgx', 06:27:34 INFO - 'tsvgr_opacity', 06:27:34 INFO - 'tart', 06:27:34 INFO - 'tscrollx', 06:27:34 INFO - 'cart', 06:27:34 INFO - 'tsvg_static']}, 06:27:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:27:34 INFO - 'tsvgr_opacity', 06:27:34 INFO - 'tart', 06:27:34 INFO - 'tscrollx', 06:27:34 INFO - 'cart', 06:27:34 INFO - 'tsvg_static']}, 06:27:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:27:34 INFO - 'talos_options': ['--disable-e10s'], 06:27:34 INFO - 'tests': ['tp5o']}, 06:27:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:27:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:27:34 INFO - 'talos_options': ['--disable-e10s', 06:27:34 INFO - '--xperf_path', 06:27:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:27:34 INFO - 'tests': ['tp5n']}, 06:27:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:27:34 INFO - 'talos_options': ['--xperf_path', 06:27:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:27:34 INFO - 'tests': ['tp5n']}}, 06:27:34 INFO - 'talos.zip': {'path': '', 06:27:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:27:34 INFO - Running post-action listener: _resource_record_post_action 06:27:34 INFO - [mozharness: 2017-02-27 14:27:34.170592Z] Finished populate-webroot step (success) 06:27:34 INFO - [mozharness: 2017-02-27 14:27:34.170728Z] Running create-virtualenv step. 06:27:34 INFO - Running pre-action listener: _resource_record_pre_action 06:27:34 INFO - Running main action method: create_virtualenv 06:27:34 INFO - Creating virtualenv /builds/slave/test/build/venv 06:27:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:27:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:27:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:27:34 INFO - Using real prefix '/tools/python27' 06:27:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:27:35 INFO - Installing distribute.............................................................................................................................................................................................done. 06:27:37 INFO - Installing pip.................done. 06:27:37 INFO - Return code: 0 06:27:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:27:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:27:38 INFO - Reading from file tmpfile_stdout 06:27:38 INFO - Output received: 06:27:38 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 06:27:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:27:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108be1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c34878a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bfd30>, '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 0x1088e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b4be8>, '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.sk7xjHVoaS/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.2BbX4nGbcE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:27:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:27:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:27:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sk7xjHVoaS/Render', 06:27:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:38 INFO - 'HOME': '/Users/cltbld', 06:27:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:38 INFO - 'LOGNAME': 'cltbld', 06:27:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:38 INFO - 'MOZ_NO_REMOTE': '1', 06:27:38 INFO - 'NO_EM_RESTART': '1', 06:27:38 INFO - 'PAGER': '/bin/cat', 06:27:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:38 INFO - 'PWD': '/builds/slave/test', 06:27:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:38 INFO - 'SHELL': '/bin/bash', 06:27:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BbX4nGbcE/Listeners', 06:27:38 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 06:27:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:38 INFO - 'USER': 'cltbld', 06:27:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:38 INFO - 'XPC_FLAGS': '0x0', 06:27:38 INFO - 'XPC_SERVICE_NAME': '0', 06:27:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:38 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 06:27:38 INFO - Cleaning up... 06:27:38 INFO - Return code: 0 06:27:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:27:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108be1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c34878a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bfd30>, '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 0x1088e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b4be8>, '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.sk7xjHVoaS/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.2BbX4nGbcE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:27:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:27:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:27:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sk7xjHVoaS/Render', 06:27:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:38 INFO - 'HOME': '/Users/cltbld', 06:27:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:38 INFO - 'LOGNAME': 'cltbld', 06:27:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:38 INFO - 'MOZ_NO_REMOTE': '1', 06:27:38 INFO - 'NO_EM_RESTART': '1', 06:27:38 INFO - 'PAGER': '/bin/cat', 06:27:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:38 INFO - 'PWD': '/builds/slave/test', 06:27:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:38 INFO - 'SHELL': '/bin/bash', 06:27:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BbX4nGbcE/Listeners', 06:27:38 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 06:27:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:38 INFO - 'USER': 'cltbld', 06:27:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:38 INFO - 'XPC_FLAGS': '0x0', 06:27:38 INFO - 'XPC_SERVICE_NAME': '0', 06:27:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:38 INFO - Downloading/unpacking psutil>=3.1.1 06:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:27:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:27:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:27:42 INFO - Installing collected packages: psutil 06:27:42 INFO - Running setup.py install for psutil 06:27:42 INFO - building 'psutil._psutil_osx' extension 06:27:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 06:27:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 06:27:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 06:27:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 06:27:43 INFO - building 'psutil._psutil_posix' extension 06:27:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 06:27:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:27:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:27:43 INFO - ^ 06:27:43 INFO - 1 warning generated. 06:27:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 06:27:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:27:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:27:43 INFO - Successfully installed psutil 06:27:43 INFO - Cleaning up... 06:27:43 INFO - Return code: 0 06:27:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:27:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:27:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:43 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 0x108be1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c34878a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bfd30>, '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 0x1088e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b4be8>, '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.sk7xjHVoaS/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.2BbX4nGbcE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:27:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:27:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:27:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sk7xjHVoaS/Render', 06:27:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:43 INFO - 'HOME': '/Users/cltbld', 06:27:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:43 INFO - 'LOGNAME': 'cltbld', 06:27:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:43 INFO - 'MOZ_NO_REMOTE': '1', 06:27:43 INFO - 'NO_EM_RESTART': '1', 06:27:43 INFO - 'PAGER': '/bin/cat', 06:27:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:43 INFO - 'PWD': '/builds/slave/test', 06:27:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:43 INFO - 'SHELL': '/bin/bash', 06:27:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BbX4nGbcE/Listeners', 06:27:43 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 06:27:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:43 INFO - 'USER': 'cltbld', 06:27:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:43 INFO - 'XPC_FLAGS': '0x0', 06:27:43 INFO - 'XPC_SERVICE_NAME': '0', 06:27:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:27:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:27:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:27:46 INFO - Installing collected packages: mozsystemmonitor 06:27:46 INFO - Running setup.py install for mozsystemmonitor 06:27:47 INFO - Successfully installed mozsystemmonitor 06:27:47 INFO - Cleaning up... 06:27:47 INFO - Return code: 0 06:27:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:27: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')]} 06:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27: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')]} 06:27:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27: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', '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 0x108be1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c34878a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bfd30>, '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 0x1088e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b4be8>, '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.sk7xjHVoaS/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.2BbX4nGbcE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:27: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:27: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 jsonschema==2.5.1 06:27:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sk7xjHVoaS/Render', 06:27:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:47 INFO - 'HOME': '/Users/cltbld', 06:27:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:47 INFO - 'LOGNAME': 'cltbld', 06:27:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:47 INFO - 'MOZ_NO_REMOTE': '1', 06:27:47 INFO - 'NO_EM_RESTART': '1', 06:27:47 INFO - 'PAGER': '/bin/cat', 06:27:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:47 INFO - 'PWD': '/builds/slave/test', 06:27:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:47 INFO - 'SHELL': '/bin/bash', 06:27:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BbX4nGbcE/Listeners', 06:27:47 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 06:27:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:47 INFO - 'USER': 'cltbld', 06:27:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:47 INFO - 'XPC_FLAGS': '0x0', 06:27:47 INFO - 'XPC_SERVICE_NAME': '0', 06:27:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:47 INFO - Downloading/unpacking jsonschema==2.5.1 06:27: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 06:27: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 06:27: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 06:27: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 06:27: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 06:27: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 06:27:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:27:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:27: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 06:27: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 06:27: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 06:27: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 06:27: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 06:27: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 06:27:50 INFO - Downloading functools32-3.2.3-2.tar.gz 06:27:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:27:50 INFO - warning: no files found matching '*.txt' 06:27:50 INFO - no previously-included directories found matching 'build' 06:27:50 INFO - no previously-included directories found matching 'dist' 06:27:50 INFO - no previously-included directories found matching '.git*' 06:27:50 INFO - Installing collected packages: jsonschema, functools32 06:27:50 INFO - Running setup.py install for functools32 06:27:50 INFO - warning: no files found matching '*.txt' 06:27:50 INFO - no previously-included directories found matching 'build' 06:27:50 INFO - no previously-included directories found matching 'dist' 06:27:50 INFO - no previously-included directories found matching '.git*' 06:27:50 INFO - Successfully installed jsonschema functools32 06:27:50 INFO - Cleaning up... 06:27:51 INFO - Return code: 0 06:27:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:27:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:51 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 0x108be1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c34878a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bfd30>, '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 0x1088e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b4be8>, '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.sk7xjHVoaS/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.2BbX4nGbcE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:27:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:27:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sk7xjHVoaS/Render', 06:27:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:51 INFO - 'HOME': '/Users/cltbld', 06:27:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:51 INFO - 'LOGNAME': 'cltbld', 06:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:51 INFO - 'MOZ_NO_REMOTE': '1', 06:27:51 INFO - 'NO_EM_RESTART': '1', 06:27:51 INFO - 'PAGER': '/bin/cat', 06:27:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:51 INFO - 'PWD': '/builds/slave/test', 06:27:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:51 INFO - 'SHELL': '/bin/bash', 06:27:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BbX4nGbcE/Listeners', 06:27:51 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 06:27:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:51 INFO - 'USER': 'cltbld', 06:27:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:51 INFO - 'XPC_FLAGS': '0x0', 06:27:51 INFO - 'XPC_SERVICE_NAME': '0', 06:27:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:27:51 INFO - Cleaning up... 06:27:51 INFO - Return code: 0 06:27:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:27:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:51 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 0x108be1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c34878a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bfd30>, '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 0x1088e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b4be8>, '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.sk7xjHVoaS/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.2BbX4nGbcE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:27:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:27:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sk7xjHVoaS/Render', 06:27:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:51 INFO - 'HOME': '/Users/cltbld', 06:27:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:51 INFO - 'LOGNAME': 'cltbld', 06:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:51 INFO - 'MOZ_NO_REMOTE': '1', 06:27:51 INFO - 'NO_EM_RESTART': '1', 06:27:51 INFO - 'PAGER': '/bin/cat', 06:27:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:51 INFO - 'PWD': '/builds/slave/test', 06:27:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:51 INFO - 'SHELL': '/bin/bash', 06:27:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BbX4nGbcE/Listeners', 06:27:51 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 06:27:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:51 INFO - 'USER': 'cltbld', 06:27:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:51 INFO - 'XPC_FLAGS': '0x0', 06:27:51 INFO - 'XPC_SERVICE_NAME': '0', 06:27:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:51 INFO - Downloading/unpacking blobuploader==1.2.4 06:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:54 INFO - Downloading blobuploader-1.2.4.tar.gz 06:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:27:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:27: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 06:27: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 06:27: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 06:27: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 06:27: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 06:27: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 06:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:27:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:27: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 06:27: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 06:27: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 06:27: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 06:27: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 06:27: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 06:27:55 INFO - Downloading docopt-0.6.1.tar.gz 06:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:27:55 INFO - Installing collected packages: blobuploader, requests, docopt 06:27:55 INFO - Running setup.py install for blobuploader 06:27:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:27:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:27:55 INFO - Running setup.py install for requests 06:27:56 INFO - Running setup.py install for docopt 06:27:56 INFO - Successfully installed blobuploader requests docopt 06:27:56 INFO - Cleaning up... 06:27:56 INFO - Return code: 0 06:27:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:27:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:56 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 0x108be1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c34878a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bfd30>, '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 0x1088e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b4be8>, '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.sk7xjHVoaS/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.2BbX4nGbcE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:27:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:27:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:27:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sk7xjHVoaS/Render', 06:27:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:56 INFO - 'HOME': '/Users/cltbld', 06:27:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:56 INFO - 'LOGNAME': 'cltbld', 06:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:56 INFO - 'MOZ_NO_REMOTE': '1', 06:27:56 INFO - 'NO_EM_RESTART': '1', 06:27:56 INFO - 'PAGER': '/bin/cat', 06:27:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:56 INFO - 'PWD': '/builds/slave/test', 06:27:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:56 INFO - 'SHELL': '/bin/bash', 06:27:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BbX4nGbcE/Listeners', 06:27:56 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 06:27:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:56 INFO - 'USER': 'cltbld', 06:27:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:56 INFO - 'XPC_FLAGS': '0x0', 06:27:56 INFO - 'XPC_SERVICE_NAME': '0', 06:27:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:27:56 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-BzCI41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:27:56 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-6nXFJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:27:56 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-fXl_ta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:27:56 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-_DYhtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:27:57 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-yUT3YR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:27:57 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-HDJGUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:27:57 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-m47nT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:27:57 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-Pger1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:27:57 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-HWeIGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:27:57 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-1ub0zH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:27:57 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-vW1cgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:27:57 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-IoCkJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:27:57 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-Ze9I9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:27:57 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-oc4qS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:27:58 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-gEgR9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:27:58 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-sYbcmR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:27:58 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-lA03A9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:27:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:27:58 INFO - Running setup.py install for manifestparser 06:27:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:27:58 INFO - Running setup.py install for mozcrash 06:27:58 INFO - Running setup.py install for mozdebug 06:27:58 INFO - Running setup.py install for mozdevice 06:27:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:27:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:27:58 INFO - Running setup.py install for mozfile 06:27:59 INFO - Running setup.py install for mozhttpd 06:27:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:27:59 INFO - Running setup.py install for mozinfo 06:27:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:27:59 INFO - Running setup.py install for mozInstall 06:27:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:27:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:27:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:27:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:27:59 INFO - Running setup.py install for mozleak 06:27:59 INFO - Running setup.py install for mozlog 06:27:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:27:59 INFO - Running setup.py install for moznetwork 06:27:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:28:00 INFO - Running setup.py install for mozprocess 06:28:00 INFO - Running setup.py install for mozprofile 06:28:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:28:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:28:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:28:00 INFO - Running setup.py install for mozrunner 06:28:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:28:00 INFO - Running setup.py install for mozscreenshot 06:28:00 INFO - Running setup.py install for moztest 06:28:00 INFO - Running setup.py install for mozversion 06:28:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:28:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:28:00 INFO - Cleaning up... 06:28:00 INFO - Return code: 0 06:28:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:00 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 0x108be1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c34878a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bfd30>, '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 0x1088e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b4be8>, '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.sk7xjHVoaS/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.2BbX4nGbcE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:28:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:28:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sk7xjHVoaS/Render', 06:28:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:00 INFO - 'HOME': '/Users/cltbld', 06:28:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:00 INFO - 'LOGNAME': 'cltbld', 06:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:00 INFO - 'MOZ_NO_REMOTE': '1', 06:28:00 INFO - 'NO_EM_RESTART': '1', 06:28:00 INFO - 'PAGER': '/bin/cat', 06:28:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:00 INFO - 'PWD': '/builds/slave/test', 06:28:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:00 INFO - 'SHELL': '/bin/bash', 06:28:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BbX4nGbcE/Listeners', 06:28:00 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 06:28:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:00 INFO - 'USER': 'cltbld', 06:28:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:00 INFO - 'XPC_FLAGS': '0x0', 06:28:00 INFO - 'XPC_SERVICE_NAME': '0', 06:28:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:28:01 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-avcWOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:28:01 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-IzLbD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:28:01 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-iTjKRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:28:01 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-pQT2Sn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:28:01 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-brznlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 06:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:28:01 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-pEffZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 06:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:28:01 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-RwjUhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:28:01 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-zPhsb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:28:02 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-yfuHg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:28:02 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-buFNeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:28:02 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-5z_G6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:28:02 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-hxbkNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:28:02 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-dz6_Jl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:28:02 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-Tcdknr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:28:02 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-WxhS5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:28:02 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-pwaXDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:28:02 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-5_XGYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:28:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:05 INFO - Downloading blessings-1.6.tar.gz 06:28:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:28:05 INFO - Installing collected packages: blessings 06:28:05 INFO - Running setup.py install for blessings 06:28:05 INFO - Successfully installed blessings 06:28:05 INFO - Cleaning up... 06:28:06 INFO - Return code: 0 06:28:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:28:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:28:06 INFO - Reading from file tmpfile_stdout 06:28:06 INFO - Current package versions: 06:28:06 INFO - blessings == 1.6 06:28:06 INFO - blobuploader == 1.2.4 06:28:06 INFO - docopt == 0.6.1 06:28:06 INFO - functools32 == 3.2.3-2 06:28:06 INFO - jsonschema == 2.5.1 06:28:06 INFO - manifestparser == 1.1 06:28:06 INFO - mozInstall == 1.12 06:28:06 INFO - mozcrash == 1.0 06:28:06 INFO - mozdebug == 0.1 06:28:06 INFO - mozdevice == 0.49 06:28:06 INFO - mozfile == 1.2 06:28:06 INFO - mozhttpd == 0.7 06:28:06 INFO - mozinfo == 0.9 06:28:06 INFO - mozleak == 0.1 06:28:06 INFO - mozlog == 3.4 06:28:06 INFO - moznetwork == 0.27 06:28:06 INFO - mozprocess == 0.25 06:28:06 INFO - mozprofile == 0.28 06:28:06 INFO - mozrunner == 6.13 06:28:06 INFO - mozscreenshot == 0.1 06:28:06 INFO - mozsystemmonitor == 0.3 06:28:06 INFO - moztest == 0.8 06:28:06 INFO - mozversion == 1.4 06:28:06 INFO - psutil == 3.1.1 06:28:06 INFO - requests == 1.2.3 06:28:06 INFO - wsgiref == 0.1.2 06:28:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:28:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:06 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 0x108be1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c34878a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bfd30>, '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 0x1088e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b4be8>, '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.sk7xjHVoaS/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.2BbX4nGbcE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:28:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:28:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sk7xjHVoaS/Render', 06:28:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:06 INFO - 'HOME': '/Users/cltbld', 06:28:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:06 INFO - 'LOGNAME': 'cltbld', 06:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:06 INFO - 'MOZ_NO_REMOTE': '1', 06:28:06 INFO - 'NO_EM_RESTART': '1', 06:28:06 INFO - 'PAGER': '/bin/cat', 06:28:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:06 INFO - 'PWD': '/builds/slave/test', 06:28:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:06 INFO - 'SHELL': '/bin/bash', 06:28:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BbX4nGbcE/Listeners', 06:28:06 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 06:28:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:06 INFO - 'USER': 'cltbld', 06:28:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:06 INFO - 'XPC_FLAGS': '0x0', 06:28:06 INFO - 'XPC_SERVICE_NAME': '0', 06:28:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:28:06 INFO - Cleaning up... 06:28:06 INFO - Return code: 0 06:28:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:28:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108be1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c34878a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bfd30>, '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 0x1088e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b4be8>, '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.sk7xjHVoaS/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.2BbX4nGbcE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:28:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:28:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sk7xjHVoaS/Render', 06:28:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:06 INFO - 'HOME': '/Users/cltbld', 06:28:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:06 INFO - 'LOGNAME': 'cltbld', 06:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:06 INFO - 'MOZ_NO_REMOTE': '1', 06:28:06 INFO - 'NO_EM_RESTART': '1', 06:28:06 INFO - 'PAGER': '/bin/cat', 06:28:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:06 INFO - 'PWD': '/builds/slave/test', 06:28:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:06 INFO - 'SHELL': '/bin/bash', 06:28:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BbX4nGbcE/Listeners', 06:28:06 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 06:28:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:06 INFO - 'USER': 'cltbld', 06:28:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:06 INFO - 'XPC_FLAGS': '0x0', 06:28:06 INFO - 'XPC_SERVICE_NAME': '0', 06:28:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:28:06 INFO - Cleaning up... 06:28:06 INFO - Return code: 0 06:28:06 INFO - Running post-action listener: _resource_record_post_action 06:28:06 INFO - Running post-action listener: _start_resource_monitoring 06:28:06 INFO - Starting resource monitoring. 06:28:06 INFO - [mozharness: 2017-02-27 14:28:06.854675Z] Finished create-virtualenv step (success) 06:28:06 INFO - [mozharness: 2017-02-27 14:28:06.855621Z] Running install step. 06:28:06 INFO - Running pre-action listener: _resource_record_pre_action 06:28:06 INFO - Running main action method: install 06:28:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:28:07 INFO - Reading from file tmpfile_stdout 06:28:07 INFO - Detecting whether we're running mozinstall >=1.0... 06:28:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:28:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:28:07 INFO - Reading from file tmpfile_stdout 06:28:07 INFO - Output received: 06:28:07 INFO - Usage: mozinstall [options] installer 06:28:07 INFO - Options: 06:28:07 INFO - -h, --help show this help message and exit 06:28:07 INFO - -d DEST, --destination=DEST 06:28:07 INFO - Directory to install application into. [default: 06:28:07 INFO - "/builds/slave/test"] 06:28:07 INFO - --app=APP Application being installed. [default: firefox] 06:28:07 INFO - mkdir: /builds/slave/test/build/application 06:28:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:28:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:28:27 INFO - Reading from file tmpfile_stdout 06:28:27 INFO - Output received: 06:28:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:28:27 INFO - Running post-action listener: _resource_record_post_action 06:28:27 INFO - [mozharness: 2017-02-27 14:28:27.603800Z] Finished install step (success) 06:28:27 INFO - [mozharness: 2017-02-27 14:28:27.603925Z] Running run-tests step. 06:28:27 INFO - Running pre-action listener: _resource_record_pre_action 06:28:27 INFO - Running main action method: run_tests 06:28:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:28:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:28:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:28:27 INFO - 2017-02-27 06:28:27.618 screenresolution[1628:8202] starting screenresolution argv=screenresolution get 06:28:27 INFO - 2017-02-27 06:28:27.621 screenresolution[1628:8202] Display 0: 1600x1200x32@60 06:28:27 INFO - 2017-02-27 06:28:27.626 screenresolution[1629:8205] starting screenresolution argv=screenresolution list 06:28:27 INFO - Available Modes on Display 0 06:28:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:28:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:28:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:28:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:28:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:28:27 INFO - Intel Iris: 06:28:27 INFO - Chipset Model: Intel Iris 06:28:27 INFO - Type: GPU 06:28:27 INFO - Bus: Built-In 06:28:27 INFO - VRAM (Dynamic, Max): 1536 MB 06:28:27 INFO - Vendor: Intel (0x8086) 06:28:27 INFO - Device ID: 0x0a2e 06:28:27 INFO - Revision ID: 0x0009 06:28:27 INFO - Displays: 06:28:27 INFO - Display: 06:28:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:28:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:28:27 INFO - Display Serial Number: 1600x1200 60 06:28:27 INFO - Main Display: Yes 06:28:27 INFO - Mirror: Off 06:28:27 INFO - Online: Yes 06:28:27 INFO - Rotation: Supported 06:28:27 INFO - Return code: 0 06:28:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:28:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:28:27 INFO - Python 2.7.3 06:28:27 INFO - Return code: 0 06:28:27 INFO - grabbing minidump binary from tooltool 06:28:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:27 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 0x1089bfd30>, '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 0x1088e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b4be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:28:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:28:27 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 06:28:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:28:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:28:27 INFO - Return code: 0 06:28:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:28:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:28:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:28:27 INFO - ENV: RUST_BACKTRACE is now 1 06:28:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:28:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:28:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0219', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 06:28:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0219 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 06:28:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sk7xjHVoaS/Render', 06:28:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:27 INFO - 'HOME': '/Users/cltbld', 06:28:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:27 INFO - 'LOGNAME': 'cltbld', 06:28:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:28:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:28:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:27 INFO - 'MOZ_NO_REMOTE': '1', 06:28:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:28:27 INFO - 'NO_EM_RESTART': '1', 06:28:27 INFO - 'PAGER': '/bin/cat', 06:28:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:27 INFO - 'PWD': '/builds/slave/test', 06:28:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:28:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:27 INFO - 'RUST_BACKTRACE': '1', 06:28:27 INFO - 'SHELL': '/bin/bash', 06:28:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BbX4nGbcE/Listeners', 06:28:27 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 06:28:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:27 INFO - 'USER': 'cltbld', 06:28:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:27 INFO - 'XPC_FLAGS': '0x0', 06:28:27 INFO - 'XPC_SERVICE_NAME': '0', 06:28:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0219', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 06:28:28 INFO - mozversion application_buildid: 20170227050709 06:28:28 INFO - mozversion application_changeset: 43f52c13c08d29cc2b13c3e650d4412017dc74bd 06:28:28 INFO - mozversion application_display_name: Nightly 06:28:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:28:28 INFO - mozversion application_name: Firefox 06:28:28 INFO - mozversion application_remotingname: firefox 06:28:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:28:28 INFO - mozversion application_vendor: Mozilla 06:28:28 INFO - mozversion application_version: 54.0a1 06:28:28 INFO - mozversion platform_buildid: 20170227050709 06:28:28 INFO - mozversion platform_changeset: 43f52c13c08d29cc2b13c3e650d4412017dc74bd 06:28:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:28:28 INFO - mozversion platform_version: 54.0a1 06:28:28 INFO - using testdate: 1488205708 06:28:28 INFO - actual date: 1488205708 06:28:28 INFO - starting webserver on 'localhost:49241' 06:28:28 INFO - SUITE-START | Running 2 tests 06:28:28 INFO - TEST-START | dromaeo_css 06:28:28 INFO - operating with platform_type : mac_ 06:28:28 INFO - Initialising browser for dromaeo_css test... 06:28:28 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpPvn0Yn/profile http://localhost:49241/getInfo.html) 06:28:31 INFO - PROCESS | 1639 | 2017-02-27 06:28:31.232 plugin-container[1641:8387] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b4b, name = 'com.apple.tsm.portname' 06:28:31 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:28:31 INFO - PROCESS | 1639 | 2017-02-27 06:28:31.235 plugin-container[1641:8387] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 06:28:31 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:28:31 INFO - PROCESS | 1639 | 2017-02-27 06:28:31.235 plugin-container[1641:8387] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:28:31 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 06:28:31 INFO - PROCESS | 1639 | colorDepth:24 06:28:31 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 06:28:31 INFO - PROCESS | 1639 | __metrics 06:28:31 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 06:28:32 INFO - TEST-INFO | 1639: exit 0 06:28:32 INFO - Browser initialized. 06:28:32 INFO - Running cycle 1/1 for dromaeo_css test... 06:28:32 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpPvn0Yn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:28:34 INFO - PROCESS | 1643 | 2017-02-27 06:28:34.681 plugin-container[1644:8522] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 06:28:34 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:28:34 INFO - PROCESS | 1643 | 2017-02-27 06:28:34.682 plugin-container[1644:8522] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 06:28:34 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:28:34 INFO - PROCESS | 1643 | 2017-02-27 06:28:34.682 plugin-container[1644:8522] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:28:44 INFO - PROCESS | 1643 | RSS: Main: 226258944 06:28:44 INFO - PROCESS | 1643 | 06:31:47 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 06:31:49 INFO - PROCESS | 1643 | RSS: Main: 250568704 06:31:49 INFO - PROCESS | 1643 | 06:33:32 INFO - PROCESS | 1643 | ************************* 06:33:32 INFO - PROCESS | 1643 | A coding exception was thrown and uncaught in a Task. 06:33:32 INFO - PROCESS | 1643 | 06:33:32 ERROR - PROCESS | 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:33:32 INFO - PROCESS | 1643 | Full stack: 06:33:32 INFO - PROCESS | 1643 | ************************* 06:34:51 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 06:34:52 INFO - PROCESS | 1643 | RSS: Main: 257191936 06:34:52 INFO - PROCESS | 1643 | 06:37:54 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 06:37:55 INFO - PROCESS | 1643 | RSS: Main: 268132352 06:37:55 INFO - PROCESS | 1643 | 06:40:57 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 06:40:57 INFO - PROCESS | 1643 | RSS: Main: 261079040 06:40:57 INFO - PROCESS | 1643 | 06:44:00 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 06:44:01 INFO - PROCESS | 1643 | RSS: Main: 271204352 06:44:01 INFO - PROCESS | 1643 | 06:47:04 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 06:47:04 INFO - PROCESS | 1643 | RSS: Main: 264818688 06:47:04 INFO - PROCESS | 1643 | 06:47:04 INFO - PROCESS | 1643 | __start_tp_report 06:47:04 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 06:47:04 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 06:47:04 INFO - PROCESS | 1643 | |i|pagename|runs| 06:47:04 INFO - PROCESS | 1643 | |0;dojo.html;3076.6308043034837;3285.589301337333;3312.619048809386;3340.248444100078;3348.112750121217;4918.890111915217;4979.812919731375;5486.725663716813;5553.500184983356;5573.442655734424;5638.577106717003;5646.2942132233475;5677.659340439576;5682.8350188211425;5690.374058853523;5009.9749501252445;5165.276861239429;5211.166213405856;5273.841784746463;5349.384820745606;5213.765380557884;5248.632595718302;5254.133068043769;5270.209468579706;5276.577873770108;5090.643654944174;5141.0232055909255;5166.43169251382;5215.269862219292;5236.450172731885;5167.354080739908;5174.508421699932;5174.844754657329;5178.689278643294;5212.921806172911;5005.124103281947;5143.434222235551;5148.716820574867;5176.637635365526;5204.167333226704;5342.27879236302;5488.9725551371985;5563.860903477405;5570.916436253459;5611.130274807411;6146.877062458726;6159.87680246397;6208.50331973441;6232.719527621268;6297.118403423525;6270.592411493284;6273.498120150377;6277.811665650038;6287.842803929892;6318.842028949313;5624.746886390122;5768.134779783024;5790.768369265183;5857.531397488191;5880.764769409176;6138.480361557146;6211.9689401552705;6214.37856214384;6217.751289948352;6260.092286618506;5884.852878678067;6084.269887613515;6117.571769976148;6142.621558143003;6177.135201071854;6224.813255602248;6262.09199666055;6283.748650054038;6296.055591661196;6318.6524741138755;6035.6076855004285;6191.721372538246;6290.968545157337;6292.4966002719675;6293.930031894589;6087.482563982023;6118.95422233728;6166.753329866755;6168.907466388007;6212.0371342441995;4973.303737476756;4984.102861484895;4986.950130498722;5000.474950130257;5029.2958985742025;4444.866654000385;4452.332150177434;4469.843555475542;4478.0148367359125;4482.081173359454;4637.652176086807;5648.096304591328;5686.488216060575;5724.484796368347;5784.566157538202;6245.281792593819;6258.593191442542;6273.529485288622;6281.874362512815;6293.118963345135;5815.244018277682;5843.29880414353;5851.678157701367;5865.002949498596;5890.469857712741;5301.496357845998;5341.599380046512;5342.572594192533;5405.594580406485;5477.561795056386;6185.597936134137;6242;6242.719077641608;6257.311695713468;6315.179026726496;6221.160143380766;6225.3774622537385;6235.750569977332;6236.906446403217;6249.125122482947;6242.096963746614;6266.624002559861;6319;6358.364163583605;6363.61818290904;6013.879722405615;6087.69561521931;6103.246756470893;6105.755769769159;6153.353897840662;6180.721867516063;6215.160953271251;6221.06683997404;6284.183056202664;6289.056641503811;4707.270373092176;5455.509004189642;5954.940450595439;6059.21230240066;6120.234970628671;6152.354002829638;6173.506119510339;6174.228221472316;6176.073588961692;6186.969065154645;5668.971655141698;5952.8809423812145;6038.486728628116;6075.392460753889;6092.8781424372155;5327.9200811987075;6019.916415045333;6054.1826853376;6127.922628246602;6148.877022459616;4658.93011604833;4975.527324904128;6040.728167232397;6066.3023752269055;6071.483923866344 06:47:04 INFO - PROCESS | 1643 | |1;ext.html;709.8083517450289;716.7598854383781;804.0311424733197;928.3826255540066;932.3426983976298;30073.699263007362;30177.69822301779;35961.640383596154;38069.04827379317;38144.80927595358;36736.448953265724;36930.44604330937;37001;37058;37103.072423189435;6415.294317625069;7828.725994590162;9199.126083022102;10522.947385263064;10841.295315804487;10768.838467422995;10856.34861908288;10870.347779133237;10908.400037997906;10916.508757105948;10878;10881.619143329985;10901.291416057971;10925.852785457531;10927.508262128224;10825.729356766065;10846.29499082561;10886.891131088667;10919.071878890303;10931.18016148788;10571.68284951445;10595.25833191677;10626.043656070991;10634.840477392845;10646.787064258671;33829;33979;34246.65753342459;34300.48549271763;34488.655113449044;3638.199596088856;3639.9636003640153;3641.88465049815;3661.0847288177956;3675.5589329280656;25489.235322940156;25559.233223003153;25604.35989100269;26386.868065659743;26418.86790566054;2562.6027965665353;2882.524383476745;3058.357744873579;3064.5249986252165;3087.8764849405998;272920;301503;322538.3873080643;335478;383837;43017.56982430198;43234.351484727755;43383;43409.34885976713;43463.78268108639;44137.33793993029;44573.55426445694;44607;44610.77694611506;44643.55356446394;12737.745245095046;12846.10077294599;12864.485420583072;12869.613911582668;12878.420471078824;11082.667519974413;11247.381394023407;11339.39193985388;11378.772424551462;11465.484053217626;11728.296302221894;11765.647030589096;11814.763704725858;11833.290002599872;11912.344821034927;4673.135469938073;5778.711064446761;5862.912056319158;5874.647521148745;5875.735591898375;815.7552734179723;877.2893955895745;909.2271569166156;966.0339660339661;967.5210770668564;25506.23481295564;26233.081842135434;26330;26340.473190536082;26543.86728066347;1008.1531513528671;1015.9238057145595;1042.6663467690419;1053.0838170791308;1057.539970112988;11932.327660944806;12338.198017129038;13557.52548660812;13701.177929324273;13763.655908602164;380225;383691;383930;383938;384179;41597.79201104036;50385;50500;50663.49336506661;50823;1359.463012110211;1435.103060587133;1437.5328018394064;1437.7915202295608;1440.9207493587953;24158.63762043606;25437.473751574966;25669.61495577605;25996.35009124787;26023.73976260213;40866.1826763469;40899;40914;40982;41078.9730256746;350055;350728;351213.4878651283;351496;351698;18853;21894.23370182036;21982.670259945793;21988.230411935507;22062.33812985613;38211.42682859815;38519.84440466791;38758.80620596879;38863.80568097141;39281.803590981865;29870.701292986792;31340.843295783376;31815.363692726478;31850;32262.677373225968;31542;32127.03618891437;32434.837825811665;32774.836125819216;33804;32780.672193277765;33313.167170820925;33426.83006094775;33557.83221083977;33575.66424335823;25028;28552.286192845346;29197.416051679273;29323.560146597392;29947.65235564361;22548.88725556362;22789;23038;23093.88453057724;23245.95393207276 06:47:04 INFO - PROCESS | 1643 | |2;jquery.html;361.43074657414564;387.21009141351624;396.0534322968107;403.6770583533173;406.6421549036851;19679.803201967978;21302.680459793075;21326.253581124663;21539;21640.675389869124;21082.57834843302;21636.89181554094;21653.891730541363;21852.672209916826;21985.23051693191;5338.759755810978;5805.042168042268;5840.912386314188;5859.326177489601;5932.644041357532;5630.887382252353;5768.336641286242;5828.154917536955;5924.851878703045;5944;6006.21919150512;6040.154378387029;6058.182145410379;6063.484603808682;6076.878462430749;5813.73838177281;5932.139839723238;6015.398460153993;6057.424544668272;6058.273007239115;5146.974265128688;5871.236739223917;5874.383189765056;5877.05967045275;5890;13221.07452478327;13469.528566500223;13501;13579.932100339534;13603;2453.7055553333535;2491.0641186924618;2498.8250822442437;2516.987415062931;2536.1377131775102;7950.681972721084;9768.51157442125;9814.312998090136;10271.69184924446;10352.275340726152;1705.6503416799526;1758.1297257857443;1771.76081229035;1788.6065065685527;1790.7224380221087;108008.45995769971;122063;122856;123267.383663082;133973.99039014324;11409.828852567218;11811.468483918245;12076.396180190955;12088.456019479145;12090.214136081302;15452;15657.217139142997;15690.921545392428;15715.4499592516;15732.213389330716;8500.53247071417;8663.133686631287;8700.738977830675;8706.259967902672;8880;8231.34149268057;8347.916520834835;8564.914350856536;8569.785755356166;8584.356173287028;4711.246200607886;4804.543568361;4831.299461578052;4842.491538388489;4899.804007839718;2025.9696104558443;2064.607724532334;2064.64900966836;2065.3390914907086;2074.6161960037443;1043.1811028342715;1046.7857285548728;1048.1038711901215;1051.5530899367739;1098.2477003252798;18309.633807324044;21168.78831211699;21534.923253837245;21574.029168687448;21684.217891753866;1063.7978784031009;1080.2168427889717;1104.4974536585835;1106.551846502168;1114.57089020726;10436.63471778484;11192.552297907992;11223.887761122447;11227.326360418401;11230.606928757454;708290;709578;711897;718352;725917;102846.48576757069;128536.42927141405;129078.09219078017;143551.56448435382;157214;1012.7518757904213;1030.5311083457009;1082.4966390628394;1086.4839201379282;1089.8637670291214;19763.505912352306;20054.398368048984;20240.898795506517;20323;20407.489812754207;52122;57789.42210578008;58515.12227316679;59312.40687593069;59351.10973335317;65647;87306;88047;89029.55485222532;89501;8261.421700480909;8390.622421990904;8628.741137765877;8716.564171791259;8738.432001919855;8510.829783404173;9399.765005874908;12560.309182995023;12845.550405735754;13141.802872956721;9101.453912765255;10876.728081798019;11304.095672346028;11498.08015358786;11551.133664975328;9144.588493517675;9716.465594392377;10072.143867771321;10946.616868409568;11307.943460282646;11299.265547739371;11531.654050378502;11563.942180289045;11585.420728963687;11906.226095303777;8481.15188481147;8524;8559.315254779727;8581.570921454027;8613.440126391764;7792.727254546064;10609.787804244026;10993.670189893995;11346.546138154712;11371.943140284246 06:47:04 INFO - PROCESS | 1643 | |3;mootools.html;1429.9499517516888;1464.729025130351;1475.7860110284003;1476.3504058214385;1484.154032201645;8252.463589866662;8447.788805279872;8664.306855451556;8692.130786921305;8699.608517616707;8620.224179823814;8733.34499912506;8736.825263494726;8761;8779.429337093101;2262.1969200933636;2317.9536409271805;2319.3389883883096;2320.1879342230254;2322.814174866015;2295.1393227539675;2329.1382188590246;2342.992513219315;2343.0627748900406;2347.812175025994;1925.4704956137034;2300.1834348806183;2300.919467818627;2307.1117619716447;2324.790768830805;2260.6269965455763;2263.4454558633074;2282.8287878409187;2287.1994801819396;2292.3466811958597;2283.2008796920945;2317.2353123469215;2331.2423462374627;2335.8131349492;2337.754535773753;8603.612837422268;8628.697995570188;8665;8683.739487815375;8708.303335733126;5138.691678499302;5281.445448227958;5287.074761916688;5287.788488460457;5288.497592728685;2910.3306239565018;3267.591551056118;3271.689189526991;3546.2730140321432;3549.4675798630155;1016.8983101689846;1131.7679875625477;1199.7060720123625;1223.608445297505;1254.9811752823714;10052.24608154384;10303.433311167882;10471.319364241375;10908.072813810835;10909.945450272777;4439.977800110979;4525.094981003746;4533.659975501851;4552.726836389761;4553.089382123522;8763.474191548528;8869.979952305568;8870.51212183336;8879.378443508893;8884.777880553038;4343.1747967886;4363.432753742057;5110.565601923804;5153.742312884341;5164.302819119446;4678.087772884255;4806.591439727662;4819.638527110481;4879.194932836042;4893;3585.7848529088337;3679.098620837858;3693.8522459101873;3701.7964011979598;3770.0951771575174;1145.1869172887177;1146.9369184694922;1159.1190695071807;1159.605734050427;1162.7616338650557;774.744334369668;836.8451836410284;885.8051228729669;901.2519608724742;933.7478880702172;5328.733563321818;5351.518363347317;5423.647462914898;5708.0010998076;5799.492132045614;934.1732566678538;974.5224839828433;988.4662282367441;1003.5935446144323;1010.7372083258406;3642.581103173116;3648.416253399443;3752.6810221130963;3777.9244415111543;3838.8848334550007;9286.34995550305;10002.349847259904;10059.245556583286;10073.29486935922;10109.797804043877;9608.2793790466;10007.849882251772;10010.49947502622;10033.648822291185;10033.849492257476;7683.193264707237;7977.960110199412;7987.361011119213;7997.560134192675;8032.3172530334405;5060.51925067118;5718.25662663851;5750.281214848144;5817.563682723728;5825.766969321349;9120.224780893433;9326.533673316442;9336.06639336061;9338.95330523343;9360.344775865624;8349.624266907882;8511.531865747196;8759.956200219212;9423.858642120511;9436.764080897758;6961.199462061928;7115.146182458138;7204.603746793767;7219.530730502501;7241.782746517613;7613.809654758454;7909.564973926489;7968.521888686697;8030.919690803017;8093.676252949816;7131.144262688511;7177.712891484282;7245.2030276670575;7260.237675044255;7261.164966028974;7022.613756243456;7040.683169257293;7056.717731290691;7079.858402832017;7098.503104782616;6354.904676429765;6365.809025729235;6377.77677781294;6416;6425.807225783234;4902.9019419610695;4933.358663373739;4955.40535135786;5005.674631148963;5067.315912351785;4212.368144778307;4939.16034274167;4983.551480366785;5087.186050231945;5093.668911520739 06:47:04 INFO - PROCESS | 1643 | |4;prototype.html;317.9159067579554;350.64935064935065;358.84748981682776;369.4366091710142;373.9513863197784;5370.570354371651;5441.374241962176;5638.943610563893;5662.886742265157;5697.803461273133;5337.786488540453;5456.945430545693;5539.806106786264;5540.390557038722;5586.664800111986;3676.988828072275;3912.4913761211;4380.75148582654;4407.619885261955;4450.443694538183;3373.5108409280638;4024.315866302721;4175.331946888498;4419.469663640351;4429.867103986886;3955.3473676843287;4136.4415803866395;4160.355144952538;4163.583641635827;4187.073945984851;3508.438649816043;3703.4259689748133;4143.037728024462;4178.310578754502;4200.348945913388;3127.5777770000986;3287.386527710476;3614.945775813365;3616.8553257869653;3640.6359364063646;5147.576861387211;5152.398038990324;5470.753816078286;5488.076025641645;5563.8887222255735;3938.0154961259686;4121.9381709274085;4447.3773671685985;4812.951870481321;4903.779329930162;5222.430373423254;5226.660267082628;5292.550133238679;5924.851878703067;5931.495822855062;3349.7487688423466;3747.7938713370754;3773.8301126650827;3786.2427514497213;3976.7812770297624;5204.921926171072;5624.353199382042;5647.745851436695;5738.971305143447;6046.699959508683;3413.914652133667;3440.415129428003;3550.3609350317192;3907.8632247871756;4030.536488303824;3562.3943929532043;3678.503402040744;4051.7771522565954;4077.959220407817;4111.753294802322;4359.433273674402;4539.977300113478;4821;4824.951750482521;4858.02839432115;5532.3914369419335;5667.773289068474;5856.765729370777;5980.700964951822;6016.548758843104;1136.170595465315;1194.7431302270054;1245.6671361643137;1322.0877594459791;1329.514727124612;828.8052307707684;842.2335674536265;857.3752738560393;886.0652012127139;912.7307444303947;4448.977755111204;4869.440014398318;4901.901961960741;5270.552003079782;5283.762230699628;5140.768665409991;5756.898731962803;5791.276090488689;5809.419058094241;5942.64344139353;4498.887527811766;4852.223644216909;5025.597952163818;5208.187578707531;5311.28297679816;2739.9726002739717;2919.3285544324926;3039.9088027359217;3043.36089421224;3088.583041289442;5393.191021346829;5983.910241346383;6099.512039036867;6102.420270074336;6103.6032657877095;5059.165237735806;5512.531434828004;5597.944020559742;5912.320083190402;5918.171455996164;5219.686818790885;5986.521078313812;5996.991008012277;6051.788187413419;6075.969620151871;4966.577840883565;5025.547700706954;5625.683728876173;5874.676892770939;5882.411758824083;4780.808767649255;5087.796488140433;5183.585313175013;5818.48719332968;5819.941800582109;5668.631538949955;5893.44012318829;5998.0806142034135;6080.787172448943;6178.66017369049;3414.6073201582135;3983.422403751382;4013.4180543821567;4016.710475029205;4031.588943869622;5181.715005674724;5196.038732834438;5998.25021872266;6073.689839037646;6108.78619248342;5480.534154596904;5500.339959204921;5820.970895145667;5848.2689663792025;5869.442402971711;5006.5994720423005;5381.596380271417;5860.619059761272;5878.617889837147;5894.469497745224;4899.804007839646;5144.9742751286;5443.755031023535;5775.855603609775;5823.82528524145;4592.632589392907;5023.623228257969;5482.890342193054;5863.859691212762;5955.880882382415;5344.866378340573;5735.684537350486;5770.6249093808765;5797.681127537857;5917 06:47:04 INFO - PROCESS | 1643 | |5;yui.html;699.0981633692537;715.1347068145798;782.7456076775048;783.6708582395394;818.4393690322128;14487.637809054766;14974.475893343708;15341.079535227895;15394.61513462164;15417.69164616707;11276;13317.733645327113;13369.264690442022;13453.260070696108;13455.259960702182;939.5396255834643;954.4654993203793;955.4601650067724;955.9856602150974;956.5073986896754;920.7237828651412;942.241495596045;942.4062840410531;954.746992047108;966.5940305071887;1013.3615822032147;1014.756458449974;1014.8629934958797;1017.8015287019034;1018.836986082227;1003.9899601004006;1007.450939238117;1010.15651930638;1011.1910471622673;1012.6050840172338;739.9334059934632;747.805570551655;749.134749364485;750.5496701978824;751.3050428353805;15299.541013769605;15483.5354939352;15760.290786914502;16059.437919672871;16288.511344659679;13856.861431385658;13933.024688271826;14102.506412275623;14156.787648185285;14257.429702811874;11464.426778661034;11621;11766.588169414115;11813.586524471602;12080.395980200954;3184.442722523549;3470.340635279289;3629.8185090745355;3667.0465678923674;3697.7226707997006;99727.50136249345;102204.7795220472;102986.48506757493;105963;108645.91354086436;10684.786304273872;10712.839307410239;10723.785524289626;10974.396408197466;11105.107397621596;21215.787842121383;21574.568508630055;21698.457538561346;21753.891230543744;21834.454138646663;7618.923810761821;7634.045744281965;7779.299863012243;7848.411369147337;7936.841263174704;4109.239790638681;4233.767142807175;4235.385869048971;4250.55369186231;4252.064545799919;3030.9696903031127;3514.191735900706;3567.3757092509227;3572.6427357264065;3617.4935509028755;595.4373117404011;685.3505085420157;690;700.2857085772483;708.5713143548182;648.2367959151102;663.5123184459419;690.2097098821821;697.7313734212263;732.3006528765017;9795.951020244996;9839.720836291237;9926.235407004679;10088.899111008795;10208.387496750218;664.7274617406937;698.3470455124475;700.1283402164237;706.6078326528826;712.8752468318023;4508.661850361171;4804.567588916944;4853.369062021915;4857.368542089505;4899.314096026559;68886;94825.52587237158;95935;97394;98031;71654;78081;80689;85730.57134714411;86942.26115477782;49965.75017124964;63984.68007659932;70827;74377;75125.6243718767;8784.868226976729;10360.015798499131;10609.257351985287;10856.511456984612;10936.289141205796;42267.36598950957;48210.27684584664;48333.3416662087;49121.50878491312;49732.502674972784;63661.36338636554;63769.40576485625;64205.678971604844;65028.67485662542;65982;11358.26171298863;12723.618291451272;12733.681657958254;12957.898578620923;13305.268210248529;13810.93094534521;13964.581062568139;13977.58067257984;14371.640708982359;14690.779638305221;12826.743465130832;12989;13084.672883177996;13210.867891320964;13446.327683615977;11251.493682784458;11908.166428349932;12949;12965.935170324088;13121.606351809462;10620.681379558626;12782.424790884246;12864.292463914575;12974.091813572959;13022.348882556023;10367.792644146924;10422.843657345298;10648.680539583826;10794.676159715222;10893.727656808644;8736.301095912184;9118.635254590008;10101.393916365041;10629.9468502657;10666.813303272966 06:47:04 INFO - PROCESS | 1643 | __end_tp_report 06:47:04 INFO - PROCESS | 1643 | __start_cc_report 06:47:04 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,429 06:47:04 INFO - PROCESS | 1643 | __end_cc_report 06:47:04 INFO - PROCESS | 1643 | __startTimestamp1488206824579__endTimestamp 06:47:04 INFO - PROCESS | 1643 | 06:47:04 INFO - PROCESS | 1643 | ------- Summary: start ------- 06:47:04 INFO - PROCESS | 1643 | Number of tests: 6 06:47:04 INFO - PROCESS | 1643 | 06:47:04 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5690.62 Median:5952.88 stddev:668.36 (11.2%) stddev-sans-first:638.17 06:47:04 INFO - PROCESS | 1643 | Values: 3076.6 3285.6 3312.6 3340.2 3348.1 4918.9 4979.8 5486.7 5553.5 5573.4 5638.6 5646.3 5677.7 5682.8 5690.4 5010.0 5165.3 5211.2 5273.8 5349.4 5213.8 5248.6 5254.1 5270.2 5276.6 5090.6 5141.0 5166.4 5215.3 5236.5 5167.4 5174.5 5174.8 5178.7 5212.9 5005.1 5143.4 5148.7 5176.6 5204.2 5342.3 5489.0 5563.9 5570.9 5611.1 6146.9 6159.9 6208.5 6232.7 6297.1 6270.6 6273.5 6277.8 6287.8 6318.8 5624.7 5768.1 5790.8 5857.5 5880.8 6138.5 6212.0 6214.4 6217.8 6260.1 5884.9 6084.3 6117.6 6142.6 6177.1 6224.8 6262.1 6283.7 6296.1 6318.7 6035.6 6191.7 6291.0 6292.5 6293.9 6087.5 6119.0 6166.8 6168.9 6212.0 4973.3 4984.1 4987.0 5000.5 5029.3 4444.9 4452.3 4469.8 4478.0 4482.1 4637.7 5648.1 5686.5 5724.5 5784.6 6245.3 6258.6 6273.5 6281.9 6293.1 5815.2 5843.3 5851.7 5865.0 5890.5 5301.5 5341.6 5342.6 5405.6 5477.6 6185.6 6242.0 6242.7 6257.3 6315.2 6221.2 6225.4 6235.8 6236.9 6249.1 6242.1 6266.6 6319.0 6358.4 6363.6 6013.9 6087.7 6103.2 6105.8 6153.4 6180.7 6215.2 6221.1 6284.2 6289.1 4707.3 5455.5 5954.9 6059.2 6120.2 6152.4 6173.5 6174.2 6176.1 6187.0 5669.0 5952.9 6038.5 6075.4 6092.9 5327.9 6019.9 6054.2 6127.9 6148.9 4658.9 4975.5 6040.7 6066.3 6071.5 06:47:04 INFO - PROCESS | 1643 | 06:47:04 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:48459.73 Median:3076.20 stddev:93462.29 (3038.2%) stddev-sans-first:93655.72 06:47:04 INFO - PROCESS | 1643 | Values: 709.8 716.8 804.0 928.4 932.3 30073.7 30177.7 35961.6 38069.0 38144.8 36736.4 36930.4 37001.0 37058.0 37103.1 6415.3 7828.7 9199.1 10522.9 10841.3 10768.8 10856.3 10870.3 10908.4 10916.5 10878.0 10881.6 10901.3 10925.9 10927.5 10825.7 10846.3 10886.9 10919.1 10931.2 10571.7 10595.3 10626.0 10634.8 10646.8 33829.0 33979.0 34246.7 34300.5 34488.7 3638.2 3640.0 3641.9 3661.1 3675.6 25489.2 25559.2 25604.4 26386.9 26418.9 2562.6 2882.5 3058.4 3064.5 3087.9 272920.0 301503.0 322538.4 335478.0 383837.0 43017.6 43234.4 43383.0 43409.3 43463.8 44137.3 44573.6 44607.0 44610.8 44643.6 12737.7 12846.1 12864.5 12869.6 12878.4 11082.7 11247.4 11339.4 11378.8 11465.5 11728.3 11765.6 11814.8 11833.3 11912.3 4673.1 5778.7 5862.9 5874.6 5875.7 815.8 877.3 909.2 966.0 967.5 25506.2 26233.1 26330.0 26340.5 26543.9 1008.2 1015.9 1042.7 1053.1 1057.5 11932.3 12338.2 13557.5 13701.2 13763.7 380225.0 383691.0 383930.0 383938.0 384179.0 41597.8 50385.0 50500.0 50663.5 50823.0 1359.5 1435.1 1437.5 1437.8 1440.9 24158.6 25437.5 25669.6 25996.4 26023.7 40866.2 40899.0 40914.0 40982.0 41079.0 350055.0 350728.0 351213.5 351496.0 351698.0 18853.0 21894.2 21982.7 21988.2 22062.3 38211.4 38519.8 38758.8 38863.8 39281.8 29870.7 31340.8 31815.4 31850.0 32262.7 31542.0 32127.0 32434.8 32774.8 33804.0 32780.7 33313.2 33426.8 33557.8 33575.7 25028.0 28552.3 29197.4 29323.6 29947.7 22548.9 22789.0 23038.0 23093.9 23246.0 06:47:04 INFO - PROCESS | 1643 | 06:47:04 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:38572.03 Median:21638.78 stddev:118685.02 (548.5%) stddev-sans-first:118983.28 06:47:04 INFO - PROCESS | 1643 | Values: 361.4 387.2 396.1 403.7 406.6 19679.8 21302.7 21326.3 21539.0 21640.7 21082.6 21636.9 21653.9 21852.7 21985.2 5338.8 5805.0 5840.9 5859.3 5932.6 5630.9 5768.3 5828.2 5924.9 5944.0 6006.2 6040.2 6058.2 6063.5 6076.9 5813.7 5932.1 6015.4 6057.4 6058.3 5147.0 5871.2 5874.4 5877.1 5890.0 13221.1 13469.5 13501.0 13579.9 13603.0 2453.7 2491.1 2498.8 2517.0 2536.1 7950.7 9768.5 9814.3 10271.7 10352.3 1705.7 1758.1 1771.8 1788.6 1790.7 108008.5 122063.0 122856.0 123267.4 133974.0 11409.8 11811.5 12076.4 12088.5 12090.2 15452.0 15657.2 15690.9 15715.4 15732.2 8500.5 8663.1 8700.7 8706.3 8880.0 8231.3 8347.9 8564.9 8569.8 8584.4 4711.2 4804.5 4831.3 4842.5 4899.8 2026.0 2064.6 2064.6 2065.3 2074.6 1043.2 1046.8 1048.1 1051.6 1098.2 18309.6 21168.8 21534.9 21574.0 21684.2 1063.8 1080.2 1104.5 1106.6 1114.6 10436.6 11192.6 11223.9 11227.3 11230.6 708290.0 709578.0 711897.0 718352.0 725917.0 102846.5 128536.4 129078.1 143551.6 157214.0 1012.8 1030.5 1082.5 1086.5 1089.9 19763.5 20054.4 20240.9 20323.0 20407.5 52122.0 57789.4 58515.1 59312.4 59351.1 65647.0 87306.0 88047.0 89029.6 89501.0 8261.4 8390.6 8628.7 8716.6 8738.4 8510.8 9399.8 12560.3 12845.6 13141.8 9101.5 10876.7 11304.1 11498.1 11551.1 9144.6 9716.5 10072.1 10946.6 11307.9 11299.3 11531.7 11563.9 11585.4 11906.2 8481.2 8524.0 8559.3 8581.6 8613.4 7792.7 10609.8 10993.7 11346.5 11371.9 06:47:04 INFO - PROCESS | 1643 | 06:47:04 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5424.20 Median:5077.25 stddev:2961.08 (58.3%) stddev-sans-first:2954.17 06:47:04 INFO - PROCESS | 1643 | Values: 1429.9 1464.7 1475.8 1476.4 1484.2 8252.5 8447.8 8664.3 8692.1 8699.6 8620.2 8733.3 8736.8 8761.0 8779.4 2262.2 2318.0 2319.3 2320.2 2322.8 2295.1 2329.1 2343.0 2343.1 2347.8 1925.5 2300.2 2300.9 2307.1 2324.8 2260.6 2263.4 2282.8 2287.2 2292.3 2283.2 2317.2 2331.2 2335.8 2337.8 8603.6 8628.7 8665.0 8683.7 8708.3 5138.7 5281.4 5287.1 5287.8 5288.5 2910.3 3267.6 3271.7 3546.3 3549.5 1016.9 1131.8 1199.7 1223.6 1255.0 10052.2 10303.4 10471.3 10908.1 10909.9 4440.0 4525.1 4533.7 4552.7 4553.1 8763.5 8870.0 8870.5 8879.4 8884.8 4343.2 4363.4 5110.6 5153.7 5164.3 4678.1 4806.6 4819.6 4879.2 4893.0 3585.8 3679.1 3693.9 3701.8 3770.1 1145.2 1146.9 1159.1 1159.6 1162.8 774.7 836.8 885.8 901.3 933.7 5328.7 5351.5 5423.6 5708.0 5799.5 934.2 974.5 988.5 1003.6 1010.7 3642.6 3648.4 3752.7 3777.9 3838.9 9286.3 10002.3 10059.2 10073.3 10109.8 9608.3 10007.8 10010.5 10033.6 10033.8 7683.2 7978.0 7987.4 7997.6 8032.3 5060.5 5718.3 5750.3 5817.6 5825.8 9120.2 9326.5 9336.1 9339.0 9360.3 8349.6 8511.5 8760.0 9423.9 9436.8 6961.2 7115.1 7204.6 7219.5 7241.8 7613.8 7909.6 7968.5 8030.9 8093.7 7131.1 7177.7 7245.2 7260.2 7261.2 7022.6 7040.7 7056.7 7079.9 7098.5 6354.9 6365.8 6377.8 6416.0 6425.8 4902.9 4933.4 4955.4 5005.7 5067.3 4212.4 4939.2 4983.6 5087.2 5093.7 06:47:04 INFO - PROCESS | 1643 | 06:47:04 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4618.18 Median:5189.81 stddev:1441.88 (27.8%) stddev-sans-first:1409.34 06:47:04 INFO - PROCESS | 1643 | Values: 317.9 350.6 358.8 369.4 374.0 5370.6 5441.4 5638.9 5662.9 5697.8 5337.8 5456.9 5539.8 5540.4 5586.7 3677.0 3912.5 4380.8 4407.6 4450.4 3373.5 4024.3 4175.3 4419.5 4429.9 3955.3 4136.4 4160.4 4163.6 4187.1 3508.4 3703.4 4143.0 4178.3 4200.3 3127.6 3287.4 3614.9 3616.9 3640.6 5147.6 5152.4 5470.8 5488.1 5563.9 3938.0 4121.9 4447.4 4813.0 4903.8 5222.4 5226.7 5292.6 5924.9 5931.5 3349.7 3747.8 3773.8 3786.2 3976.8 5204.9 5624.4 5647.7 5739.0 6046.7 3413.9 3440.4 3550.4 3907.9 4030.5 3562.4 3678.5 4051.8 4078.0 4111.8 4359.4 4540.0 4821.0 4825.0 4858.0 5532.4 5667.8 5856.8 5980.7 6016.5 1136.2 1194.7 1245.7 1322.1 1329.5 828.8 842.2 857.4 886.1 912.7 4449.0 4869.4 4901.9 5270.6 5283.8 5140.8 5756.9 5791.3 5809.4 5942.6 4498.9 4852.2 5025.6 5208.2 5311.3 2740.0 2919.3 3039.9 3043.4 3088.6 5393.2 5983.9 6099.5 6102.4 6103.6 5059.2 5512.5 5597.9 5912.3 5918.2 5219.7 5986.5 5997.0 6051.8 6076.0 4966.6 5025.5 5625.7 5874.7 5882.4 4780.8 5087.8 5183.6 5818.5 5819.9 5668.6 5893.4 5998.1 6080.8 6178.7 3414.6 3983.4 4013.4 4016.7 4031.6 5181.7 5196.0 5998.3 6073.7 6108.8 5480.5 5500.3 5821.0 5848.3 5869.4 5006.6 5381.6 5860.6 5878.6 5894.5 4899.8 5145.0 5443.8 5775.9 5823.8 4592.6 5023.6 5482.9 5863.9 5955.9 5344.9 5735.7 5770.6 5797.7 5917.0 06:47:04 INFO - PROCESS | 1643 | 06:47:04 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:19260.64 Median:3492.27 stddev:27122.84 (776.7%) stddev-sans-first:27163.11 06:47:04 INFO - PROCESS | 1643 | Values: 699.1 715.1 782.7 783.7 818.4 14487.6 14974.5 15341.1 15394.6 15417.7 11276.0 13317.7 13369.3 13453.3 13455.3 939.5 954.5 955.5 956.0 956.5 920.7 942.2 942.4 954.7 966.6 1013.4 1014.8 1014.9 1017.8 1018.8 1004.0 1007.5 1010.2 1011.2 1012.6 739.9 747.8 749.1 750.5 751.3 15299.5 15483.5 15760.3 16059.4 16288.5 13856.9 13933.0 14102.5 14156.8 14257.4 11464.4 11621.0 11766.6 11813.6 12080.4 3184.4 3470.3 3629.8 3667.0 3697.7 99727.5 102204.8 102986.5 105963.0 108645.9 10684.8 10712.8 10723.8 10974.4 11105.1 21215.8 21574.6 21698.5 21753.9 21834.5 7618.9 7634.0 7779.3 7848.4 7936.8 4109.2 4233.8 4235.4 4250.6 4252.1 3031.0 3514.2 3567.4 3572.6 3617.5 595.4 685.4 690.0 700.3 708.6 648.2 663.5 690.2 697.7 732.3 9796.0 9839.7 9926.2 10088.9 10208.4 664.7 698.3 700.1 706.6 712.9 4508.7 4804.6 4853.4 4857.4 4899.3 68886.0 94825.5 95935.0 97394.0 98031.0 71654.0 78081.0 80689.0 85730.6 86942.3 49965.8 63984.7 70827.0 74377.0 75125.6 8784.9 10360.0 10609.3 10856.5 10936.3 42267.4 48210.3 48333.3 49121.5 49732.5 63661.4 63769.4 64205.7 65028.7 65982.0 11358.3 12723.6 12733.7 12957.9 13305.3 13810.9 13964.6 13977.6 14371.6 14690.8 12826.7 12989.0 13084.7 13210.9 13446.3 11251.5 11908.2 12949.0 12965.9 13121.6 10620.7 12782.4 12864.3 12974.1 13022.3 10367.8 10422.8 10648.7 10794.7 10893.7 8736.3 9118.6 10101.4 10629.9 10666.8 06:47:04 INFO - PROCESS | 1643 | -------- Summary: end -------- 06:47:04 INFO - PROCESS | 1643 | 06:47:05 INFO - TEST-INFO | 1643: exit 0 06:47:05 INFO - TEST-OK | dromaeo_css | took 1117058ms 06:47:05 INFO - TEST-START | kraken 06:47:05 INFO - operating with platform_type : mac_ 06:47:05 INFO - Initialising browser for kraken test... 06:47:05 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmp7Nehf0/profile http://localhost:49241/getInfo.html) 06:47:08 INFO - PROCESS | 1651 | 2017-02-27 06:47:08.302 plugin-container[1652:10797] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 06:47:08 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:47:08 INFO - PROCESS | 1651 | 2017-02-27 06:47:08.305 plugin-container[1652:10797] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910f, name = 'com.apple.CFPasteboardClient' 06:47:08 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:47:08 INFO - PROCESS | 1651 | 2017-02-27 06:47:08.305 plugin-container[1652:10797] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:47:08 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 06:47:08 INFO - PROCESS | 1651 | colorDepth:24 06:47:08 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 06:47:08 INFO - PROCESS | 1651 | __metrics 06:47:08 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 06:47:09 INFO - TEST-INFO | 1651: exit 0 06:47:09 INFO - Browser initialized. 06:47:09 INFO - Running cycle 1/1 for kraken test... 06:47:09 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmp7Nehf0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:47:11 INFO - PROCESS | 1653 | 2017-02-27 06:47:11.661 plugin-container[1654:10927] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b47, name = 'com.apple.tsm.portname' 06:47:11 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:47:11 INFO - PROCESS | 1653 | 2017-02-27 06:47:11.662 plugin-container[1654:10927] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9017, name = 'com.apple.CFPasteboardClient' 06:47:11 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:47:11 INFO - PROCESS | 1653 | 2017-02-27 06:47:11.662 plugin-container[1654:10927] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:47:21 INFO - PROCESS | 1653 | RSS: Main: 226525184 06:47:21 INFO - PROCESS | 1653 | 06:47:24 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 06:47:25 INFO - PROCESS | 1653 | RSS: Main: 240730112 06:47:25 INFO - PROCESS | 1653 | 06:47:28 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 06:47:28 INFO - PROCESS | 1653 | RSS: Main: 248848384 06:47:28 INFO - PROCESS | 1653 | 06:47:32 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 06:47:33 INFO - PROCESS | 1653 | RSS: Main: 251490304 06:47:33 INFO - PROCESS | 1653 | 06:47:35 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 06:47:35 INFO - PROCESS | 1653 | RSS: Main: 252702720 06:47:35 INFO - PROCESS | 1653 | 06:47:37 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:47:38 INFO - PROCESS | 1653 | RSS: Main: 254287872 06:47:38 INFO - PROCESS | 1653 | 06:47:41 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 06:47:42 INFO - PROCESS | 1653 | RSS: Main: 259821568 06:47:42 INFO - PROCESS | 1653 | 06:47:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 06:47:46 INFO - PROCESS | 1653 | RSS: Main: 259325952 06:47:46 INFO - PROCESS | 1653 | 06:47:49 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 06:47:50 INFO - PROCESS | 1653 | RSS: Main: 258940928 06:47:50 INFO - PROCESS | 1653 | 06:47:51 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:47:52 INFO - PROCESS | 1653 | RSS: Main: 258953216 06:47:52 INFO - PROCESS | 1653 | 06:47:54 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:47:55 INFO - PROCESS | 1653 | RSS: Main: 258953216 06:47:55 INFO - PROCESS | 1653 | 06:47:57 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:47:58 INFO - PROCESS | 1653 | RSS: Main: 258965504 06:47:58 INFO - PROCESS | 1653 | 06:48:01 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:48:01 INFO - PROCESS | 1653 | RSS: Main: 259276800 06:48:01 INFO - PROCESS | 1653 | 06:48:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:48:05 INFO - PROCESS | 1653 | RSS: Main: 259055616 06:48:05 INFO - PROCESS | 1653 | 06:48:07 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 06:48:07 INFO - PROCESS | 1653 | RSS: Main: 262520832 06:48:07 INFO - PROCESS | 1653 | 06:48:07 INFO - PROCESS | 1653 | __start_tp_report 06:48:07 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 06:48:07 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 06:48:07 INFO - PROCESS | 1653 | |i|pagename|runs| 06:48:07 INFO - PROCESS | 1653 | |0;ai-astar;107;118;118;118;116;118;123;126;118;117 06:48:07 INFO - PROCESS | 1653 | |1;audio-beat-detection;141;116;153;143;144;147;145;119;131;117 06:48:07 INFO - PROCESS | 1653 | |2;audio-dft;208;207;254;241;286;267;196;197;201;197 06:48:07 INFO - PROCESS | 1653 | |3;audio-fft;107;84;85;112;64;63;63;63;75;59 06:48:07 INFO - PROCESS | 1653 | |4;audio-oscillator;92;73;82;71;72;68;105;104;99;94 06:48:07 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;90;89;90;90;88;91;116;90;89;90 06:48:07 INFO - PROCESS | 1653 | |6;imaging-darkroom;98;97;96;99;97;97;144;153;103;118 06:48:07 INFO - PROCESS | 1653 | |7;imaging-desaturate;94;89;101;92;91;92;96;93;93;134 06:48:07 INFO - PROCESS | 1653 | |8;json-parse-financial;67;66;86;102;102;102;101;100;66;67 06:48:07 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;63;71;72;72;68;64;66;56;78;94 06:48:07 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;90;85;87;90;91;89;88;89;92;91 06:48:07 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;154;166;136;141;152;143;138;143;224;144 06:48:07 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;174;179;175;189;181;162;234;165;229;233 06:48:07 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;70;68;69;76;76;74;71;67;70;76 06:48:07 INFO - PROCESS | 1653 | __end_tp_report 06:48:07 INFO - PROCESS | 1653 | __start_cc_report 06:48:07 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1012 06:48:07 INFO - PROCESS | 1653 | __end_cc_report 06:48:07 INFO - PROCESS | 1653 | __startTimestamp1488206887671__endTimestamp 06:48:07 INFO - PROCESS | 1653 | 06:48:07 INFO - PROCESS | 1653 | ------- Summary: start ------- 06:48:07 INFO - PROCESS | 1653 | Number of tests: 14 06:48:07 INFO - PROCESS | 1653 | 06:48:07 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:117.90 Median:118.00 stddev:4.89 (4.1%) stddev-sans-first:3.22 06:48:07 INFO - PROCESS | 1653 | Values: 107.0 118.0 118.0 118.0 116.0 118.0 123.0 126.0 118.0 117.0 06:48:07 INFO - PROCESS | 1653 | 06:48:07 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:135.60 Median:143.50 stddev:13.75 (9.6%) stddev-sans-first:14.45 06:48:07 INFO - PROCESS | 1653 | Values: 141.0 116.0 153.0 143.0 144.0 147.0 145.0 119.0 131.0 117.0 06:48:07 INFO - PROCESS | 1653 | 06:48:07 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:225.40 Median:224.50 stddev:33.63 (15.0%) stddev-sans-first:35.07 06:48:07 INFO - PROCESS | 1653 | Values: 208.0 207.0 254.0 241.0 286.0 267.0 196.0 197.0 201.0 197.0 06:48:07 INFO - PROCESS | 1653 | 06:48:07 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:77.50 Median:63.50 stddev:19.21 (30.2%) stddev-sans-first:17.15 06:48:07 INFO - PROCESS | 1653 | Values: 107.0 84.0 85.0 112.0 64.0 63.0 63.0 63.0 75.0 59.0 06:48:07 INFO - PROCESS | 1653 | 06:48:07 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:86.00 Median:77.50 stddev:14.47 (18.7%) stddev-sans-first:15.18 06:48:07 INFO - PROCESS | 1653 | Values: 92.0 73.0 82.0 71.0 72.0 68.0 105.0 104.0 99.0 94.0 06:48:07 INFO - PROCESS | 1653 | 06:48:07 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:92.30 Median:90.00 stddev:8.37 (9.3%) stddev-sans-first:8.83 06:48:07 INFO - PROCESS | 1653 | Values: 90.0 89.0 90.0 90.0 88.0 91.0 116.0 90.0 89.0 90.0 06:48:07 INFO - PROCESS | 1653 | 06:48:07 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:110.20 Median:97.00 stddev:21.31 (22.0%) stddev-sans-first:22.14 06:48:07 INFO - PROCESS | 1653 | Values: 98.0 97.0 96.0 99.0 97.0 97.0 144.0 153.0 103.0 118.0 06:48:07 INFO - PROCESS | 1653 | 06:48:07 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:97.50 Median:92.50 stddev:13.23 (14.3%) stddev-sans-first:13.97 06:48:07 INFO - PROCESS | 1653 | Values: 94.0 89.0 101.0 92.0 91.0 92.0 96.0 93.0 93.0 134.0 06:48:07 INFO - PROCESS | 1653 | 06:48:07 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:85.90 Median:66.00 stddev:17.36 (26.3%) stddev-sans-first:17.01 06:48:07 INFO - PROCESS | 1653 | Values: 67.0 66.0 86.0 102.0 102.0 102.0 101.0 100.0 66.0 67.0 06:48:07 INFO - PROCESS | 1653 | 06:48:07 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.50 stddev:10.27 (14.4%) stddev-sans-first:10.53 06:48:07 INFO - PROCESS | 1653 | Values: 63.0 71.0 72.0 72.0 68.0 64.0 66.0 56.0 78.0 94.0 06:48:07 INFO - PROCESS | 1653 | 06:48:07 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:90.00 stddev:2.10 (2.3%) stddev-sans-first:2.20 06:48:07 INFO - PROCESS | 1653 | Values: 90.0 85.0 87.0 90.0 91.0 89.0 88.0 89.0 92.0 91.0 06:48:07 INFO - PROCESS | 1653 | 06:48:07 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:154.10 Median:148.00 stddev:26.12 (17.6%) stddev-sans-first:27.70 06:48:07 INFO - PROCESS | 1653 | Values: 154.0 166.0 136.0 141.0 152.0 143.0 138.0 143.0 224.0 144.0 06:48:07 INFO - PROCESS | 1653 | 06:48:07 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.10 Median:185.00 stddev:28.59 (15.5%) stddev-sans-first:29.56 06:48:07 INFO - PROCESS | 1653 | Values: 174.0 179.0 175.0 189.0 181.0 162.0 234.0 165.0 229.0 233.0 06:48:07 INFO - PROCESS | 1653 | 06:48:07 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.70 Median:72.50 stddev:3.50 (4.8%) stddev-sans-first:3.66 06:48:07 INFO - PROCESS | 1653 | Values: 70.0 68.0 69.0 76.0 76.0 74.0 71.0 67.0 70.0 76.0 06:48:07 INFO - PROCESS | 1653 | -------- Summary: end -------- 06:48:07 INFO - PROCESS | 1653 | 06:48:08 INFO - TEST-INFO | 1653: exit 0 06:48:08 INFO - TEST-OK | kraken | took 63409ms 06:48:08 INFO - SUITE-END | took 1180s 06:48:08 INFO - Completed test suite (00:19:40) 06:48:08 INFO - javascript benchmark 06:48:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7114.915620731874, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3076.6308043034837, 3285.589301337333, 3312.619048809386, 3340.248444100078, 3348.112750121217, 4918.890111915217, 4979.812919731375, 5486.725663716813, 5553.500184983356, 5573.442655734424, 5638.577106717003, 5646.2942132233475, 5677.659340439576, 5682.8350188211425, 5690.374058853523, 5009.9749501252445, 5165.276861239429, 5211.166213405856, 5273.841784746463, 5349.384820745606, 5213.765380557884, 5248.632595718302, 5254.133068043769, 5270.209468579706, 5276.577873770108, 5090.643654944174, 5141.0232055909255, 5166.43169251382, 5215.269862219292, 5236.450172731885, 5167.354080739908, 5174.508421699932, 5174.844754657329, 5178.689278643294, 5212.921806172911, 5005.124103281947, 5143.434222235551, 5148.716820574867, 5176.637635365526, 5204.167333226704, 5342.27879236302, 5488.9725551371985, 5563.860903477405, 5570.916436253459, 5611.130274807411, 6146.877062458726, 6159.87680246397, 6208.50331973441, 6232.719527621268, 6297.118403423525, 6270.592411493284, 6273.498120150377, 6277.811665650038, 6287.842803929892, 6318.842028949313, 5624.746886390122, 5768.134779783024, 5790.768369265183, 5857.531397488191, 5880.764769409176, 6138.480361557146, 6211.9689401552705, 6214.37856214384, 6217.751289948352, 6260.092286618506, 5884.852878678067, 6084.269887613515, 6117.571769976148, 6142.621558143003, 6177.135201071854, 6224.813255602248, 6262.09199666055, 6283.748650054038, 6296.055591661196, 6318.6524741138755, 6035.6076855004285, 6191.721372538246, 6290.968545157337, 6292.4966002719675, 6293.930031894589, 6087.482563982023, 6118.95422233728, 6166.753329866755, 6168.907466388007, 6212.0371342441995, 4973.303737476756, 4984.102861484895, 4986.950130498722, 5000.474950130257, 5029.2958985742025, 4444.866654000385, 4452.332150177434, 4469.843555475542, 4478.0148367359125, 4482.081173359454, 4637.652176086807, 5648.096304591328, 5686.488216060575, 5724.484796368347, 5784.566157538202, 6245.281792593819, 6258.593191442542, 6273.529485288622, 6281.874362512815, 6293.118963345135, 5815.244018277682, 5843.29880414353, 5851.678157701367, 5865.002949498596, 5890.469857712741, 5301.496357845998, 5341.599380046512, 5342.572594192533, 5405.594580406485, 5477.561795056386, 6185.597936134137, 6242.0, 6242.719077641608, 6257.311695713468, 6315.179026726496, 6221.160143380766, 6225.3774622537385, 6235.750569977332, 6236.906446403217, 6249.125122482947, 6242.096963746614, 6266.624002559861, 6319.0, 6358.364163583605, 6363.61818290904, 6013.879722405615, 6087.69561521931, 6103.246756470893, 6105.755769769159, 6153.353897840662, 6180.721867516063, 6215.160953271251, 6221.06683997404, 6284.183056202664, 6289.056641503811, 4707.270373092176, 5455.509004189642, 5954.940450595439, 6059.21230240066, 6120.234970628671, 6152.354002829638, 6173.506119510339, 6174.228221472316, 6176.073588961692, 6186.969065154645, 5668.971655141698, 5952.8809423812145, 6038.486728628116, 6075.392460753889, 6092.8781424372155, 5327.9200811987075, 6019.916415045333, 6054.1826853376, 6127.922628246602, 6148.877022459616, 4658.93011604833, 4975.527324904128, 6040.728167232397, 6066.3023752269055, 6071.483923866344], "value": 5647.831139747809, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.8083517450289, 716.7598854383781, 804.0311424733197, 928.3826255540066, 932.3426983976298, 30073.699263007362, 30177.69822301779, 35961.640383596154, 38069.04827379317, 38144.80927595358, 36736.448953265724, 36930.44604330937, 37001.0, 37058.0, 37103.072423189435, 6415.294317625069, 7828.725994590162, 9199.126083022102, 10522.947385263064, 10841.295315804487, 10768.838467422995, 10856.34861908288, 10870.347779133237, 10908.400037997906, 10916.508757105948, 10878.0, 10881.619143329985, 10901.291416057971, 10925.852785457531, 10927.508262128224, 10825.729356766065, 10846.29499082561, 10886.891131088667, 10919.071878890303, 10931.18016148788, 10571.68284951445, 10595.25833191677, 10626.043656070991, 10634.840477392845, 10646.787064258671, 33829.0, 33979.0, 34246.65753342459, 34300.48549271763, 34488.655113449044, 3638.199596088856, 3639.9636003640153, 3641.88465049815, 3661.0847288177956, 3675.5589329280656, 25489.235322940156, 25559.233223003153, 25604.35989100269, 26386.868065659743, 26418.86790566054, 2562.6027965665353, 2882.524383476745, 3058.357744873579, 3064.5249986252165, 3087.8764849405998, 272920.0, 301503.0, 322538.3873080643, 335478.0, 383837.0, 43017.56982430198, 43234.351484727755, 43383.0, 43409.34885976713, 43463.78268108639, 44137.33793993029, 44573.55426445694, 44607.0, 44610.77694611506, 44643.55356446394, 12737.745245095046, 12846.10077294599, 12864.485420583072, 12869.613911582668, 12878.420471078824, 11082.667519974413, 11247.381394023407, 11339.39193985388, 11378.772424551462, 11465.484053217626, 11728.296302221894, 11765.647030589096, 11814.763704725858, 11833.290002599872, 11912.344821034927, 4673.135469938073, 5778.711064446761, 5862.912056319158, 5874.647521148745, 5875.735591898375, 815.7552734179723, 877.2893955895745, 909.2271569166156, 966.0339660339661, 967.5210770668564, 25506.23481295564, 26233.081842135434, 26330.0, 26340.473190536082, 26543.86728066347, 1008.1531513528671, 1015.9238057145595, 1042.6663467690419, 1053.0838170791308, 1057.539970112988, 11932.327660944806, 12338.198017129038, 13557.52548660812, 13701.177929324273, 13763.655908602164, 380225.0, 383691.0, 383930.0, 383938.0, 384179.0, 41597.79201104036, 50385.0, 50500.0, 50663.49336506661, 50823.0, 1359.463012110211, 1435.103060587133, 1437.5328018394064, 1437.7915202295608, 1440.9207493587953, 24158.63762043606, 25437.473751574966, 25669.61495577605, 25996.35009124787, 26023.73976260213, 40866.1826763469, 40899.0, 40914.0, 40982.0, 41078.9730256746, 350055.0, 350728.0, 351213.4878651283, 351496.0, 351698.0, 18853.0, 21894.23370182036, 21982.670259945793, 21988.230411935507, 22062.33812985613, 38211.42682859815, 38519.84440466791, 38758.80620596879, 38863.80568097141, 39281.803590981865, 29870.701292986792, 31340.843295783376, 31815.363692726478, 31850.0, 32262.677373225968, 31542.0, 32127.03618891437, 32434.837825811665, 32774.836125819216, 33804.0, 32780.672193277765, 33313.167170820925, 33426.83006094775, 33557.83221083977, 33575.66424335823, 25028.0, 28552.286192845346, 29197.416051679273, 29323.560146597392, 29947.65235564361, 22548.88725556362, 22789.0, 23038.0, 23093.88453057724, 23245.95393207276], "value": 17357.8491979144, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.43074657414564, 387.21009141351624, 396.0534322968107, 403.6770583533173, 406.6421549036851, 19679.803201967978, 21302.680459793075, 21326.253581124663, 21539.0, 21640.675389869124, 21082.57834843302, 21636.89181554094, 21653.891730541363, 21852.672209916826, 21985.23051693191, 5338.759755810978, 5805.042168042268, 5840.912386314188, 5859.326177489601, 5932.644041357532, 5630.887382252353, 5768.336641286242, 5828.154917536955, 5924.851878703045, 5944.0, 6006.21919150512, 6040.154378387029, 6058.182145410379, 6063.484603808682, 6076.878462430749, 5813.73838177281, 5932.139839723238, 6015.398460153993, 6057.424544668272, 6058.273007239115, 5146.974265128688, 5871.236739223917, 5874.383189765056, 5877.05967045275, 5890.0, 13221.07452478327, 13469.528566500223, 13501.0, 13579.932100339534, 13603.0, 2453.7055553333535, 2491.0641186924618, 2498.8250822442437, 2516.987415062931, 2536.1377131775102, 7950.681972721084, 9768.51157442125, 9814.312998090136, 10271.69184924446, 10352.275340726152, 1705.6503416799526, 1758.1297257857443, 1771.76081229035, 1788.6065065685527, 1790.7224380221087, 108008.45995769971, 122063.0, 122856.0, 123267.383663082, 133973.99039014324, 11409.828852567218, 11811.468483918245, 12076.396180190955, 12088.456019479145, 12090.214136081302, 15452.0, 15657.217139142997, 15690.921545392428, 15715.4499592516, 15732.213389330716, 8500.53247071417, 8663.133686631287, 8700.738977830675, 8706.259967902672, 8880.0, 8231.34149268057, 8347.916520834835, 8564.914350856536, 8569.785755356166, 8584.356173287028, 4711.246200607886, 4804.543568361, 4831.299461578052, 4842.491538388489, 4899.804007839718, 2025.9696104558443, 2064.607724532334, 2064.64900966836, 2065.3390914907086, 2074.6161960037443, 1043.1811028342715, 1046.7857285548728, 1048.1038711901215, 1051.5530899367739, 1098.2477003252798, 18309.633807324044, 21168.78831211699, 21534.923253837245, 21574.029168687448, 21684.217891753866, 1063.7978784031009, 1080.2168427889717, 1104.4974536585835, 1106.551846502168, 1114.57089020726, 10436.63471778484, 11192.552297907992, 11223.887761122447, 11227.326360418401, 11230.606928757454, 708290.0, 709578.0, 711897.0, 718352.0, 725917.0, 102846.48576757069, 128536.42927141405, 129078.09219078017, 143551.56448435382, 157214.0, 1012.7518757904213, 1030.5311083457009, 1082.4966390628394, 1086.4839201379282, 1089.8637670291214, 19763.505912352306, 20054.398368048984, 20240.898795506517, 20323.0, 20407.489812754207, 52122.0, 57789.42210578008, 58515.12227316679, 59312.40687593069, 59351.10973335317, 65647.0, 87306.0, 88047.0, 89029.55485222532, 89501.0, 8261.421700480909, 8390.622421990904, 8628.741137765877, 8716.564171791259, 8738.432001919855, 8510.829783404173, 9399.765005874908, 12560.309182995023, 12845.550405735754, 13141.802872956721, 9101.453912765255, 10876.728081798019, 11304.095672346028, 11498.08015358786, 11551.133664975328, 9144.588493517675, 9716.465594392377, 10072.143867771321, 10946.616868409568, 11307.943460282646, 11299.265547739371, 11531.654050378502, 11563.942180289045, 11585.420728963687, 11906.226095303777, 8481.15188481147, 8524.0, 8559.315254779727, 8581.570921454027, 8613.440126391764, 7792.727254546064, 10609.787804244026, 10993.670189893995, 11346.546138154712, 11371.943140284246], "value": 9704.623864541076, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1429.9499517516888, 1464.729025130351, 1475.7860110284003, 1476.3504058214385, 1484.154032201645, 8252.463589866662, 8447.788805279872, 8664.306855451556, 8692.130786921305, 8699.608517616707, 8620.224179823814, 8733.34499912506, 8736.825263494726, 8761.0, 8779.429337093101, 2262.1969200933636, 2317.9536409271805, 2319.3389883883096, 2320.1879342230254, 2322.814174866015, 2295.1393227539675, 2329.1382188590246, 2342.992513219315, 2343.0627748900406, 2347.812175025994, 1925.4704956137034, 2300.1834348806183, 2300.919467818627, 2307.1117619716447, 2324.790768830805, 2260.6269965455763, 2263.4454558633074, 2282.8287878409187, 2287.1994801819396, 2292.3466811958597, 2283.2008796920945, 2317.2353123469215, 2331.2423462374627, 2335.8131349492, 2337.754535773753, 8603.612837422268, 8628.697995570188, 8665.0, 8683.739487815375, 8708.303335733126, 5138.691678499302, 5281.445448227958, 5287.074761916688, 5287.788488460457, 5288.497592728685, 2910.3306239565018, 3267.591551056118, 3271.689189526991, 3546.2730140321432, 3549.4675798630155, 1016.8983101689846, 1131.7679875625477, 1199.7060720123625, 1223.608445297505, 1254.9811752823714, 10052.24608154384, 10303.433311167882, 10471.319364241375, 10908.072813810835, 10909.945450272777, 4439.977800110979, 4525.094981003746, 4533.659975501851, 4552.726836389761, 4553.089382123522, 8763.474191548528, 8869.979952305568, 8870.51212183336, 8879.378443508893, 8884.777880553038, 4343.1747967886, 4363.432753742057, 5110.565601923804, 5153.742312884341, 5164.302819119446, 4678.087772884255, 4806.591439727662, 4819.638527110481, 4879.194932836042, 4893.0, 3585.7848529088337, 3679.098620837858, 3693.8522459101873, 3701.7964011979598, 3770.0951771575174, 1145.1869172887177, 1146.9369184694922, 1159.1190695071807, 1159.605734050427, 1162.7616338650557, 774.744334369668, 836.8451836410284, 885.8051228729669, 901.2519608724742, 933.7478880702172, 5328.733563321818, 5351.518363347317, 5423.647462914898, 5708.0010998076, 5799.492132045614, 934.1732566678538, 974.5224839828433, 988.4662282367441, 1003.5935446144323, 1010.7372083258406, 3642.581103173116, 3648.416253399443, 3752.6810221130963, 3777.9244415111543, 3838.8848334550007, 9286.34995550305, 10002.349847259904, 10059.245556583286, 10073.29486935922, 10109.797804043877, 9608.2793790466, 10007.849882251772, 10010.49947502622, 10033.648822291185, 10033.849492257476, 7683.193264707237, 7977.960110199412, 7987.361011119213, 7997.560134192675, 8032.3172530334405, 5060.51925067118, 5718.25662663851, 5750.281214848144, 5817.563682723728, 5825.766969321349, 9120.224780893433, 9326.533673316442, 9336.06639336061, 9338.95330523343, 9360.344775865624, 8349.624266907882, 8511.531865747196, 8759.956200219212, 9423.858642120511, 9436.764080897758, 6961.199462061928, 7115.146182458138, 7204.603746793767, 7219.530730502501, 7241.782746517613, 7613.809654758454, 7909.564973926489, 7968.521888686697, 8030.919690803017, 8093.676252949816, 7131.144262688511, 7177.712891484282, 7245.2030276670575, 7260.237675044255, 7261.164966028974, 7022.613756243456, 7040.683169257293, 7056.717731290691, 7079.858402832017, 7098.503104782616, 6354.904676429765, 6365.809025729235, 6377.77677781294, 6416.0, 6425.807225783234, 4902.9019419610695, 4933.358663373739, 4955.40535135786, 5005.674631148963, 5067.315912351785, 4212.368144778307, 4939.16034274167, 4983.551480366785, 5087.186050231945, 5093.668911520739], "value": 4399.271949854014, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.9159067579554, 350.64935064935065, 358.84748981682776, 369.4366091710142, 373.9513863197784, 5370.570354371651, 5441.374241962176, 5638.943610563893, 5662.886742265157, 5697.803461273133, 5337.786488540453, 5456.945430545693, 5539.806106786264, 5540.390557038722, 5586.664800111986, 3676.988828072275, 3912.4913761211, 4380.75148582654, 4407.619885261955, 4450.443694538183, 3373.5108409280638, 4024.315866302721, 4175.331946888498, 4419.469663640351, 4429.867103986886, 3955.3473676843287, 4136.4415803866395, 4160.355144952538, 4163.583641635827, 4187.073945984851, 3508.438649816043, 3703.4259689748133, 4143.037728024462, 4178.310578754502, 4200.348945913388, 3127.5777770000986, 3287.386527710476, 3614.945775813365, 3616.8553257869653, 3640.6359364063646, 5147.576861387211, 5152.398038990324, 5470.753816078286, 5488.076025641645, 5563.8887222255735, 3938.0154961259686, 4121.9381709274085, 4447.3773671685985, 4812.951870481321, 4903.779329930162, 5222.430373423254, 5226.660267082628, 5292.550133238679, 5924.851878703067, 5931.495822855062, 3349.7487688423466, 3747.7938713370754, 3773.8301126650827, 3786.2427514497213, 3976.7812770297624, 5204.921926171072, 5624.353199382042, 5647.745851436695, 5738.971305143447, 6046.699959508683, 3413.914652133667, 3440.415129428003, 3550.3609350317192, 3907.8632247871756, 4030.536488303824, 3562.3943929532043, 3678.503402040744, 4051.7771522565954, 4077.959220407817, 4111.753294802322, 4359.433273674402, 4539.977300113478, 4821.0, 4824.951750482521, 4858.02839432115, 5532.3914369419335, 5667.773289068474, 5856.765729370777, 5980.700964951822, 6016.548758843104, 1136.170595465315, 1194.7431302270054, 1245.6671361643137, 1322.0877594459791, 1329.514727124612, 828.8052307707684, 842.2335674536265, 857.3752738560393, 886.0652012127139, 912.7307444303947, 4448.977755111204, 4869.440014398318, 4901.901961960741, 5270.552003079782, 5283.762230699628, 5140.768665409991, 5756.898731962803, 5791.276090488689, 5809.419058094241, 5942.64344139353, 4498.887527811766, 4852.223644216909, 5025.597952163818, 5208.187578707531, 5311.28297679816, 2739.9726002739717, 2919.3285544324926, 3039.9088027359217, 3043.36089421224, 3088.583041289442, 5393.191021346829, 5983.910241346383, 6099.512039036867, 6102.420270074336, 6103.6032657877095, 5059.165237735806, 5512.531434828004, 5597.944020559742, 5912.320083190402, 5918.171455996164, 5219.686818790885, 5986.521078313812, 5996.991008012277, 6051.788187413419, 6075.969620151871, 4966.577840883565, 5025.547700706954, 5625.683728876173, 5874.676892770939, 5882.411758824083, 4780.808767649255, 5087.796488140433, 5183.585313175013, 5818.48719332968, 5819.941800582109, 5668.631538949955, 5893.44012318829, 5998.0806142034135, 6080.787172448943, 6178.66017369049, 3414.6073201582135, 3983.422403751382, 4013.4180543821567, 4016.710475029205, 4031.588943869622, 5181.715005674724, 5196.038732834438, 5998.25021872266, 6073.689839037646, 6108.78619248342, 5480.534154596904, 5500.339959204921, 5820.970895145667, 5848.2689663792025, 5869.442402971711, 5006.5994720423005, 5381.596380271417, 5860.619059761272, 5878.617889837147, 5894.469497745224, 4899.804007839646, 5144.9742751286, 5443.755031023535, 5775.855603609775, 5823.82528524145, 4592.632589392907, 5023.623228257969, 5482.890342193054, 5863.859691212762, 5955.880882382415, 5344.866378340573, 5735.684537350486, 5770.6249093808765, 5797.681127537857, 5917.0], "value": 4164.882500541561, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.0981633692537, 715.1347068145798, 782.7456076775048, 783.6708582395394, 818.4393690322128, 14487.637809054766, 14974.475893343708, 15341.079535227895, 15394.61513462164, 15417.69164616707, 11276.0, 13317.733645327113, 13369.264690442022, 13453.260070696108, 13455.259960702182, 939.5396255834643, 954.4654993203793, 955.4601650067724, 955.9856602150974, 956.5073986896754, 920.7237828651412, 942.241495596045, 942.4062840410531, 954.746992047108, 966.5940305071887, 1013.3615822032147, 1014.756458449974, 1014.8629934958797, 1017.8015287019034, 1018.836986082227, 1003.9899601004006, 1007.450939238117, 1010.15651930638, 1011.1910471622673, 1012.6050840172338, 739.9334059934632, 747.805570551655, 749.134749364485, 750.5496701978824, 751.3050428353805, 15299.541013769605, 15483.5354939352, 15760.290786914502, 16059.437919672871, 16288.511344659679, 13856.861431385658, 13933.024688271826, 14102.506412275623, 14156.787648185285, 14257.429702811874, 11464.426778661034, 11621.0, 11766.588169414115, 11813.586524471602, 12080.395980200954, 3184.442722523549, 3470.340635279289, 3629.8185090745355, 3667.0465678923674, 3697.7226707997006, 99727.50136249345, 102204.7795220472, 102986.48506757493, 105963.0, 108645.91354086436, 10684.786304273872, 10712.839307410239, 10723.785524289626, 10974.396408197466, 11105.107397621596, 21215.787842121383, 21574.568508630055, 21698.457538561346, 21753.891230543744, 21834.454138646663, 7618.923810761821, 7634.045744281965, 7779.299863012243, 7848.411369147337, 7936.841263174704, 4109.239790638681, 4233.767142807175, 4235.385869048971, 4250.55369186231, 4252.064545799919, 3030.9696903031127, 3514.191735900706, 3567.3757092509227, 3572.6427357264065, 3617.4935509028755, 595.4373117404011, 685.3505085420157, 690.0, 700.2857085772483, 708.5713143548182, 648.2367959151102, 663.5123184459419, 690.2097098821821, 697.7313734212263, 732.3006528765017, 9795.951020244996, 9839.720836291237, 9926.235407004679, 10088.899111008795, 10208.387496750218, 664.7274617406937, 698.3470455124475, 700.1283402164237, 706.6078326528826, 712.8752468318023, 4508.661850361171, 4804.567588916944, 4853.369062021915, 4857.368542089505, 4899.314096026559, 68886.0, 94825.52587237158, 95935.0, 97394.0, 98031.0, 71654.0, 78081.0, 80689.0, 85730.57134714411, 86942.26115477782, 49965.75017124964, 63984.68007659932, 70827.0, 74377.0, 75125.6243718767, 8784.868226976729, 10360.015798499131, 10609.257351985287, 10856.511456984612, 10936.289141205796, 42267.36598950957, 48210.27684584664, 48333.3416662087, 49121.50878491312, 49732.502674972784, 63661.36338636554, 63769.40576485625, 64205.678971604844, 65028.67485662542, 65982.0, 11358.26171298863, 12723.618291451272, 12733.681657958254, 12957.898578620923, 13305.268210248529, 13810.93094534521, 13964.581062568139, 13977.58067257984, 14371.640708982359, 14690.779638305221, 12826.743465130832, 12989.0, 13084.672883177996, 13210.867891320964, 13446.327683615977, 11251.493682784458, 11908.166428349932, 12949.0, 12965.935170324088, 13121.606351809462, 10620.681379558626, 12782.424790884246, 12864.292463914575, 12974.091813572959, 13022.348882556023, 10367.792644146924, 10422.843657345298, 10648.680539583826, 10794.676159715222, 10893.727656808644, 8736.301095912184, 9118.635254590008, 10101.393916365041, 10629.9468502657, 10666.813303272966], "value": 7441.09524886277, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1605.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 118.0, 118.0, 116.0, 118.0, 123.0, 126.0, 118.0, 117.0], "value": 117.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 116.0, 153.0, 143.0, 144.0, 147.0, 145.0, 119.0, 131.0, 117.0], "value": 135.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 207.0, 254.0, 241.0, 286.0, 267.0, 196.0, 197.0, 201.0, 197.0], "value": 225.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 84.0, 85.0, 112.0, 64.0, 63.0, 63.0, 63.0, 75.0, 59.0], "value": 77.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 73.0, 82.0, 71.0, 72.0, 68.0, 105.0, 104.0, 99.0, 94.0], "value": 86.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 90.0, 90.0, 88.0, 91.0, 116.0, 90.0, 89.0, 90.0], "value": 92.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 96.0, 99.0, 97.0, 97.0, 144.0, 153.0, 103.0, 118.0], "value": 110.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 89.0, 101.0, 92.0, 91.0, 92.0, 96.0, 93.0, 93.0, 134.0], "value": 97.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 86.0, 102.0, 102.0, 102.0, 101.0, 100.0, 66.0, 67.0], "value": 85.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 71.0, 72.0, 72.0, 68.0, 64.0, 66.0, 56.0, 78.0, 94.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 85.0, 87.0, 90.0, 91.0, 89.0, 88.0, 89.0, 92.0, 91.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 166.0, 136.0, 141.0, 152.0, 143.0, 138.0, 143.0, 224.0, 144.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 179.0, 175.0, 189.0, 181.0, 162.0, 234.0, 165.0, 229.0, 233.0], "value": 192.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 69.0, 76.0, 76.0, 74.0, 71.0, 67.0, 70.0, 76.0], "value": 71.7, "unit": "score"}]}]} 06:48:08 INFO - Return code: 0 06:48:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:48:08 ERROR - # TBPL SUCCESS # 06:48:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:48:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:48:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:48:09 INFO - 2017-02-27 06:48:09.012 screenresolution[1658:11312] starting screenresolution argv=screenresolution get 06:48:09 INFO - 2017-02-27 06:48:09.014 screenresolution[1658:11312] Display 0: 1600x1200x32@60 06:48:09 INFO - 2017-02-27 06:48:09.021 screenresolution[1659:11315] starting screenresolution argv=screenresolution list 06:48:09 INFO - Available Modes on Display 0 06:48:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:48:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:48:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:48:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:48:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:48:09 INFO - Intel Iris: 06:48:09 INFO - Chipset Model: Intel Iris 06:48:09 INFO - Type: GPU 06:48:09 INFO - Bus: Built-In 06:48:09 INFO - VRAM (Dynamic, Max): 1536 MB 06:48:09 INFO - Vendor: Intel (0x8086) 06:48:09 INFO - Device ID: 0x0a2e 06:48:09 INFO - Revision ID: 0x0009 06:48:09 INFO - Displays: 06:48:09 INFO - Display: 06:48:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:48:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:48:09 INFO - Display Serial Number: 1600x1200 60 06:48:09 INFO - Main Display: Yes 06:48:09 INFO - Mirror: Off 06:48:09 INFO - Online: Yes 06:48:09 INFO - Rotation: Supported 06:48:09 INFO - Return code: 0 06:48:09 INFO - Running post-action listener: _resource_record_post_action 06:48:09 INFO - [mozharness: 2017-02-27 14:48:09.265458Z] Finished run-tests step (success) 06:48:09 INFO - Running post-run listener: _resource_record_post_run 06:48:09 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 33509888; Write bytes: 516020736; Read time: 197; Write time: 1713 06:48:09 INFO - TinderboxPrint: CPU usage
54.5% 06:48:09 INFO - TinderboxPrint: I/O read bytes / time
33,509,888 / 197 06:48:09 INFO - TinderboxPrint: I/O write bytes / time
516,020,736 / 1,713 06:48:09 INFO - TinderboxPrint: CPU idle
2,188.0 (45.5%) 06:48:09 INFO - TinderboxPrint: CPU system
961.4 (20.0%) 06:48:09 INFO - TinderboxPrint: CPU user
1,656.4 (34.5%) 06:48:09 INFO - TinderboxPrint: Swap in / out
239,992,832 / 0 06:48:09 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203129344; Write bytes: 204265472; Read time: 15849; Write time: 458 06:48:09 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 32584192; Write bytes: 301957632; Read time: 185; Write time: 1222 06:48:09 INFO - Running post-run listener: _upload_blobber_files 06:48:09 INFO - Blob upload gear active. 06:48:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:48:09 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 06:48:09 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:48:09 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:48:10 INFO - (blobuploader) - INFO - Open directory for files ... 06:48:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:48:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:48:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:48:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:48:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:48:11 INFO - (blobuploader) - INFO - Done attempting. 06:48:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:48:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:48:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:48:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:48:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:48:12 INFO - (blobuploader) - INFO - Done attempting. 06:48:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:48:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:48:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:48:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:48:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:48:13 INFO - (blobuploader) - INFO - Done attempting. 06:48:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:48:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:48:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:48:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:48:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:48:15 INFO - (blobuploader) - INFO - Done attempting. 06:48:15 INFO - (blobuploader) - INFO - Iteration through files over. 06:48:15 INFO - Return code: 0 06:48:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:48:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:48:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efe2f13e55fefb93e1405fff8a1e64bf220227e2bd1f3666155c290a8b94b43e71000e8c2d8cf7d727f5974c895d2be9cc350169df002e318d319de2b7065ba9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/612d74b059dd19197b7bd4530e7a1f4e3db5ba352c74cc62d09ce2b558ba00d942cdf3f28775b7737730a70c86f8ed145916a7472b9a2b72cae6dce4ad7b8f0e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4282c50a9ee29d5e3f69d26f06f946a45746fac0d96556be2ea3cb937a64316e675576c770e0d64b228f6bdf38adee61ebc3c4cd2e2b3d27986b9b72b69be567", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/676615787ae98682e36f1503fe96471378cd07b1a7ed6ba450060510473edddd3c5c06d1daa960255d2db02ab17d3f5a9c3cd2dd005e854782c45c8802c7c9b4"} 06:48:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:48:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:48:15 INFO - Contents: 06:48:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efe2f13e55fefb93e1405fff8a1e64bf220227e2bd1f3666155c290a8b94b43e71000e8c2d8cf7d727f5974c895d2be9cc350169df002e318d319de2b7065ba9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/612d74b059dd19197b7bd4530e7a1f4e3db5ba352c74cc62d09ce2b558ba00d942cdf3f28775b7737730a70c86f8ed145916a7472b9a2b72cae6dce4ad7b8f0e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4282c50a9ee29d5e3f69d26f06f946a45746fac0d96556be2ea3cb937a64316e675576c770e0d64b228f6bdf38adee61ebc3c4cd2e2b3d27986b9b72b69be567", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/676615787ae98682e36f1503fe96471378cd07b1a7ed6ba450060510473edddd3c5c06d1daa960255d2db02ab17d3f5a9c3cd2dd005e854782c45c8802c7c9b4"} 06:48:15 INFO - Running post-run listener: copy_logs_to_upload_dir 06:48:15 INFO - Copying logs to upload dir... 06:48:15 INFO - mkdir: /builds/slave/test/build/upload/logs 06:48:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.463002 ========= master_lag: -0.45 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-02-27 06:48:15.364916) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-27 06:48:15.372607) ========= 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.sk7xjHVoaS/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.2BbX4nGbcE/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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/Firefox/sha512/efe2f13e55fefb93e1405fff8a1e64bf220227e2bd1f3666155c290a8b94b43e71000e8c2d8cf7d727f5974c895d2be9cc350169df002e318d319de2b7065ba9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/612d74b059dd19197b7bd4530e7a1f4e3db5ba352c74cc62d09ce2b558ba00d942cdf3f28775b7737730a70c86f8ed145916a7472b9a2b72cae6dce4ad7b8f0e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4282c50a9ee29d5e3f69d26f06f946a45746fac0d96556be2ea3cb937a64316e675576c770e0d64b228f6bdf38adee61ebc3c4cd2e2b3d27986b9b72b69be567", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/676615787ae98682e36f1503fe96471378cd07b1a7ed6ba450060510473edddd3c5c06d1daa960255d2db02ab17d3f5a9c3cd2dd005e854782c45c8802c7c9b4"} build_url:https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010629 build_url: 'https://queue.taskcluster.net/v1/task/JsToJiVlS1Wt9ReC3Cw6NA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efe2f13e55fefb93e1405fff8a1e64bf220227e2bd1f3666155c290a8b94b43e71000e8c2d8cf7d727f5974c895d2be9cc350169df002e318d319de2b7065ba9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/612d74b059dd19197b7bd4530e7a1f4e3db5ba352c74cc62d09ce2b558ba00d942cdf3f28775b7737730a70c86f8ed145916a7472b9a2b72cae6dce4ad7b8f0e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4282c50a9ee29d5e3f69d26f06f946a45746fac0d96556be2ea3cb937a64316e675576c770e0d64b228f6bdf38adee61ebc3c4cd2e2b3d27986b9b72b69be567", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/676615787ae98682e36f1503fe96471378cd07b1a7ed6ba450060510473edddd3c5c06d1daa960255d2db02ab17d3f5a9c3cd2dd005e854782c45c8802c7c9b4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-27 06:48:15.410343) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 06:48:15.410760) ========= 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.sk7xjHVoaS/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.2BbX4nGbcE/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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.004855 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 06:48:15.459781) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-27 06:48:15.463185) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-27 06:48:15.463569) ========= ========= Total master_lag: -0.31 =========