builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0310 starttime: 1479654974.87 results: success (0) buildid: 20161120061422 builduid: 2053e9c22b65417d825bc5d9cdeda6b9 revision: b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-20 07:16:14.872124) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-20 07:16:14.872733) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-20 07:16:14.873046) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-20 07:16:14.904850) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-20 07:16:14.905316) ========= 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.KwQeDX2Oh6/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.VBwREEQ9rg/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-20 07:16:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 140M=0s 2016-11-20 07:16:15 (140 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.578734 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-20 07:16:15.507386) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-20 07:16:15.507759) ========= 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.KwQeDX2Oh6/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.VBwREEQ9rg/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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.024248 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-20 07:16:15.555870) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-20 07:16:15.556277) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 --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 b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KwQeDX2Oh6/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.VBwREEQ9rg/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-20 07:16:15,655 truncating revision to first 12 chars 2016-11-20 07:16:15,655 Setting DEBUG logging. 2016-11-20 07:16:15,655 attempt 1/10 2016-11-20 07:16:15,655 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b7f895c1dc2e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-20 07:16:16,751 unpacking tar archive at: mozilla-central-b7f895c1dc2e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.370254 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-20 07:16:16.949076) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-20 07:16:16.949469) ========= script_repo_revision: b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-20 07:16:16.950056) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-20 07:16:16.950357) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-20 07:16:16.971276) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-20 07:16:16.971707) ========= /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.KwQeDX2Oh6/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.VBwREEQ9rg/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 07:16:17 INFO - MultiFileLogger online at 20161120 07:16:17 in /builds/slave/test 07:16:17 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 07:16:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:16:17 INFO - {'append_to_log': False, 07:16:17 INFO - 'base_work_dir': '/builds/slave/test', 07:16:17 INFO - 'blob_upload_branch': 'Firefox', 07:16:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:16:17 INFO - 'branch': 'Firefox', 07:16:17 INFO - 'buildbot_json_path': 'buildprops.json', 07:16:17 INFO - 'config_files': ('talos/mac_config.py',), 07:16:17 INFO - 'default_actions': ('clobber', 07:16:17 INFO - 'read-buildbot-config', 07:16:17 INFO - 'download-and-extract', 07:16:17 INFO - 'populate-webroot', 07:16:17 INFO - 'create-virtualenv', 07:16:17 INFO - 'install', 07:16:17 INFO - 'run-tests'), 07:16:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:16:17 INFO - 'download_minidump_stackwalk': True, 07:16:17 INFO - 'download_symbols': 'ondemand', 07:16:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:16:17 INFO - 'tooltool.py': '/tools/tooltool.py', 07:16:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:16:17 INFO - '/tools/misc-python/virtualenv.py')}, 07:16:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:16:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:16:17 INFO - 'installer_path': 'installer.exe', 07:16:17 INFO - 'log_level': 'info', 07:16:17 INFO - 'log_name': 'talos', 07:16:17 INFO - 'log_to_console': True, 07:16:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:16:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:16:17 INFO - 'opt_config_files': (), 07:16:17 INFO - 'pip_index': False, 07:16:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:16:17 INFO - 'cmd': ('bash', 07:16:17 INFO - '-c', 07:16:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:16:17 INFO - 'enabled': True, 07:16:17 INFO - 'halt_on_failure': False, 07:16:17 INFO - 'name': 'check_screen_resolution'},), 07:16:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:16:17 INFO - 'cmd': ('bash', 07:16:17 INFO - '-c', 07:16:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:16:17 INFO - 'enabled': True, 07:16:17 INFO - 'halt_on_failure': False, 07:16:17 INFO - 'name': 'check_screen_resolution'},), 07:16:17 INFO - 'run_cmd_checks_enabled': True, 07:16:17 INFO - 'sps_profile': False, 07:16:17 INFO - 'sps_profile_interval': 0, 07:16:17 INFO - 'suite': 'dromaeojs-e10s', 07:16:17 INFO - 'system_bits': '32', 07:16:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:16:17 INFO - 'title': 't-yosemite-r7-0310', 07:16:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:16:17 INFO - 'use_talos_json': True, 07:16:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:16:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:16:17 INFO - 'work_dir': 'build'} 07:16:17 INFO - [mozharness: 2016-11-20 15:16:17.175036Z] Running clobber step. 07:16:17 INFO - Running pre-action listener: _resource_record_pre_action 07:16:17 INFO - Running main action method: clobber 07:16:17 INFO - rmtree: /builds/slave/test/build 07:16:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:16:19 INFO - Running post-action listener: _resource_record_post_action 07:16:19 INFO - [mozharness: 2016-11-20 15:16:19.502028Z] Finished clobber step (success) 07:16:19 INFO - [mozharness: 2016-11-20 15:16:19.502286Z] Running read-buildbot-config step. 07:16:19 INFO - Running pre-action listener: _resource_record_pre_action 07:16:19 INFO - Running main action method: read_buildbot_config 07:16:19 INFO - Using buildbot properties: 07:16:19 INFO - { 07:16:19 INFO - "project": "", 07:16:19 INFO - "product": "firefox", 07:16:19 INFO - "script_repo_revision": "production", 07:16:19 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:16:19 INFO - "repository": "", 07:16:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:16:19 INFO - "buildid": "20161120061422", 07:16:19 INFO - "pgo_build": "False", 07:16:19 INFO - "basedir": "/builds/slave/test", 07:16:19 INFO - "buildnumber": 5, 07:16:19 INFO - "slavename": "t-yosemite-r7-0310", 07:16:19 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 07:16:19 INFO - "platform": "yosemite_r7", 07:16:19 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:16:19 INFO - "branch": "mozilla-central", 07:16:19 INFO - "repo_path": "mozilla-central", 07:16:19 INFO - "revision": "b7f895c1dc2e91530240efbf50ac063a0f8a9cb5", 07:16:19 INFO - "stage_platform": "macosx64", 07:16:19 INFO - "builduid": "2053e9c22b65417d825bc5d9cdeda6b9", 07:16:19 INFO - "slavebuilddir": "test" 07:16:19 INFO - } 07:16:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 07:16:19 INFO - Running post-action listener: _resource_record_post_action 07:16:19 INFO - [mozharness: 2016-11-20 15:16:19.507775Z] Finished read-buildbot-config step (success) 07:16:19 INFO - [mozharness: 2016-11-20 15:16:19.507971Z] Running download-and-extract step. 07:16:19 INFO - Running pre-action listener: _resource_record_pre_action 07:16:19 INFO - Running main action method: download_and_extract 07:16:19 INFO - mkdir: /builds/slave/test/build/tests 07:16:19 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')]} 07:16:19 INFO - trying https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 07:16:19 INFO - Downloading https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:16:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 07:16:20 INFO - Downloaded 1199 bytes. 07:16:20 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:16:20 INFO - Using the following test package requirements: 07:16:20 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:16:20 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:16:20 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 07:16:20 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:16:20 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 07:16:20 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:16:20 INFO - u'jsshell-mac.zip'], 07:16:20 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:16:20 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 07:16:20 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:16:20 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:16:20 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 07:16:20 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:16:20 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 07:16:20 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:16:20 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 07:16:20 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:16:20 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 07:16:20 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:16:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:16:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:16:20 INFO - Fetch https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:16:21 INFO - Http code: 200 07:16:21 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 07:16:21 INFO - x-amz-cf-id: QBFUE3Aop93PtZ0mVoW3so9dX3bA3H1SuSwTd1IfmnTInsAG3OYDOA== 07:16:21 INFO - x-amz-version-id: 4vkSQ4YeXf6AeROdAKkzB0KeXE1_p_Fw 07:16:21 INFO - Expected file size: 16265486 07:16:21 INFO - Obtained file size: 16265486 07:16:27 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:16:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:16:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:16:27 INFO - Fetch https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:16:28 INFO - Http code: 200 07:16:28 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 07:16:28 INFO - x-amz-cf-id: 3Lyla8lQgZwXctVRYoSPB0ph2dFLhEKHLoyc462_Ope4AJjTJ3H2wA== 07:16:28 INFO - x-amz-version-id: 4vkSQ4YeXf6AeROdAKkzB0KeXE1_p_Fw 07:16:29 INFO - Expected file size: 16265486 07:16:29 INFO - Obtained file size: 16265486 07:16:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 07:16:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:16:35 INFO - Fetch https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 07:16:35 INFO - Http code: 200 07:16:35 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 07:16:35 INFO - x-amz-cf-id: KAwAGgTDR5p8sxe6J238jgSWFvLGYDLCXQKD3dg3iA8tjKRMSDOxsA== 07:16:35 INFO - x-amz-version-id: NJH2_8NQQxSRqHvYBIsQyEUB8N8GaYoI 07:16:36 INFO - Expected file size: 14409392 07:16:36 INFO - Obtained file size: 14409392 07:16:36 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')]} 07:16:36 INFO - trying https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:16:36 INFO - Downloading https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 07:16:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 07:16:40 INFO - Downloaded 107536658 bytes. 07:16:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:16:40 INFO - mkdir: /builds/slave/test/properties 07:16:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:16:40 INFO - Writing to file /builds/slave/test/properties/build_url 07:16:40 INFO - Contents: 07:16:40 INFO - build_url:https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:16:41 INFO - Running post-action listener: _resource_record_post_action 07:16:41 INFO - Running post-action listener: set_extra_try_arguments 07:16:41 INFO - [mozharness: 2016-11-20 15:16:41.395260Z] Finished download-and-extract step (success) 07:16:41 INFO - [mozharness: 2016-11-20 15:16:41.395416Z] Running populate-webroot step. 07:16:41 INFO - Running pre-action listener: _resource_record_pre_action 07:16:41 INFO - Running main action method: populate_webroot 07:16:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:16:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:16:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:16:41 INFO - '--tppagecycles', 07:16:41 INFO - '7'], 07:16:41 INFO - 'tests': ['tsvgm']}}, 07:16:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:16:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:16:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:16:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:16:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:16:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:16:41 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:16:41 INFO - 'talos_options': ['--disable-e10s'], 07:16:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:16:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:16:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:16:41 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:16:41 INFO - 'talos_options': ['--disable-e10s'], 07:16:41 INFO - 'tests': ['damp', 'tps']}, 07:16:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:16:41 INFO - 'tests': ['damp', 'tps']}, 07:16:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:16:41 INFO - 'tests': ['dromaeo_dom']}, 07:16:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:16:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:16:41 INFO - 'tests': ['basic_compositor_video']}, 07:16:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:16:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:16:41 INFO - 'tests': ['a11yr', 07:16:41 INFO - 'ts_paint', 07:16:41 INFO - 'tpaint', 07:16:41 INFO - 'sessionrestore', 07:16:41 INFO - 'sessionrestore_no_auto_restore', 07:16:41 INFO - 'tabpaint']}, 07:16:41 INFO - 'other-e10s': {'tests': ['a11yr', 07:16:41 INFO - 'ts_paint', 07:16:41 INFO - 'tpaint', 07:16:41 INFO - 'sessionrestore', 07:16:41 INFO - 'sessionrestore_no_auto_restore', 07:16:41 INFO - 'tabpaint']}, 07:16:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:16:41 INFO - 'ts_paint', 07:16:41 INFO - 'tpaint', 07:16:41 INFO - 'sessionrestore', 07:16:41 INFO - 'sessionrestore_no_auto_restore', 07:16:41 INFO - 'tabpaint']}, 07:16:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:16:41 INFO - 'ts_paint', 07:16:41 INFO - 'tpaint', 07:16:41 INFO - 'sessionrestore', 07:16:41 INFO - 'sessionrestore_no_auto_restore', 07:16:41 INFO - 'tabpaint']}, 07:16:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:16:41 INFO - 'tests': ['a11yr', 07:16:41 INFO - 'ts_paint', 07:16:41 INFO - 'tpaint', 07:16:41 INFO - 'sessionrestore', 07:16:41 INFO - 'sessionrestore_no_auto_restore', 07:16:41 INFO - 'tabpaint']}, 07:16:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:16:41 INFO - 'tests': ['a11yr', 07:16:41 INFO - 'ts_paint', 07:16:41 INFO - 'tpaint', 07:16:41 INFO - 'sessionrestore', 07:16:41 INFO - 'sessionrestore_no_auto_restore', 07:16:41 INFO - 'tabpaint']}, 07:16:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:16:41 INFO - 'tests': ['tsvgx', 07:16:41 INFO - 'tsvgr_opacity', 07:16:41 INFO - 'tart', 07:16:41 INFO - 'tscrollx', 07:16:41 INFO - 'cart']}, 07:16:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:16:41 INFO - 'tsvgr_opacity', 07:16:41 INFO - 'tart', 07:16:41 INFO - 'tscrollx', 07:16:41 INFO - 'cart']}, 07:16:41 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:16:41 INFO - 'talos_options': ['--disable-e10s'], 07:16:41 INFO - 'tests': ['tp5o']}, 07:16:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:16:41 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:16:41 INFO - 'talos_options': ['--disable-e10s', 07:16:41 INFO - '--xperf_path', 07:16:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:16:41 INFO - 'tests': ['tp5n']}, 07:16:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:16:41 INFO - 'talos_options': ['--xperf_path', 07:16:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:16:41 INFO - 'tests': ['tp5n']}}, 07:16:41 INFO - 'talos.zip': {'path': '', 07:16:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:16:41 INFO - Running post-action listener: _resource_record_post_action 07:16:41 INFO - [mozharness: 2016-11-20 15:16:41.413543Z] Finished populate-webroot step (success) 07:16:41 INFO - [mozharness: 2016-11-20 15:16:41.413658Z] Running create-virtualenv step. 07:16:41 INFO - Running pre-action listener: _resource_record_pre_action 07:16:41 INFO - Running main action method: create_virtualenv 07:16:41 INFO - Creating virtualenv /builds/slave/test/build/venv 07:16:41 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 07:16:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:16:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:16:41 INFO - Using real prefix '/tools/python27' 07:16:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:16:42 INFO - Installing distribute.............................................................................................................................................................................................done. 07:16:45 INFO - Installing pip.................done. 07:16:45 INFO - Return code: 0 07:16:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:16:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:16:45 INFO - Reading from file tmpfile_stdout 07:16:45 INFO - Output received: 07:16:45 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) 07:16:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:16:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b2e13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b257570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b0d6d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f7d30>, '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 0x10af5edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0efb20>, '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.KwQeDX2Oh6/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.VBwREEQ9rg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 07:16:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:16:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:16:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwQeDX2Oh6/Render', 07:16:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:45 INFO - 'HOME': '/Users/cltbld', 07:16:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:45 INFO - 'LOGNAME': 'cltbld', 07:16:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:45 INFO - 'MOZ_NO_REMOTE': '1', 07:16:45 INFO - 'NO_EM_RESTART': '1', 07:16:45 INFO - 'PAGER': '/bin/cat', 07:16:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:45 INFO - 'PWD': '/builds/slave/test', 07:16:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:45 INFO - 'SHELL': '/bin/bash', 07:16:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBwREEQ9rg/Listeners', 07:16:45 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 07:16:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:45 INFO - 'USER': 'cltbld', 07:16:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:45 INFO - 'XPC_FLAGS': '0x0', 07:16:45 INFO - 'XPC_SERVICE_NAME': '0', 07:16:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:45 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 07:16:45 INFO - Cleaning up... 07:16:45 INFO - Return code: 0 07:16:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:16:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b2e13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b257570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b0d6d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f7d30>, '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 0x10af5edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0efb20>, '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.KwQeDX2Oh6/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.VBwREEQ9rg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 07:16:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:16:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:16:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwQeDX2Oh6/Render', 07:16:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:45 INFO - 'HOME': '/Users/cltbld', 07:16:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:45 INFO - 'LOGNAME': 'cltbld', 07:16:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:45 INFO - 'MOZ_NO_REMOTE': '1', 07:16:45 INFO - 'NO_EM_RESTART': '1', 07:16:45 INFO - 'PAGER': '/bin/cat', 07:16:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:45 INFO - 'PWD': '/builds/slave/test', 07:16:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:45 INFO - 'SHELL': '/bin/bash', 07:16:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBwREEQ9rg/Listeners', 07:16:45 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 07:16:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:45 INFO - 'USER': 'cltbld', 07:16:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:45 INFO - 'XPC_FLAGS': '0x0', 07:16:45 INFO - 'XPC_SERVICE_NAME': '0', 07:16:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:46 INFO - Downloading/unpacking psutil>=3.1.1 07:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:16:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:16:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:16:49 INFO - Installing collected packages: psutil 07:16:49 INFO - Running setup.py install for psutil 07:16:49 INFO - building 'psutil._psutil_osx' extension 07:16:49 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 07:16:50 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 07:16:50 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 07:16:50 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 07:16:50 INFO - building 'psutil._psutil_posix' extension 07:16:50 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 07:16:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:16:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:16:50 INFO - ^ 07:16:50 INFO - 1 warning generated. 07:16:50 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 07:16:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:16:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:16:51 INFO - Successfully installed psutil 07:16:51 INFO - Cleaning up... 07:16:51 INFO - Return code: 0 07:16:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:16: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')]} 07:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16: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')]} 07:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b2e13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b257570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b0d6d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f7d30>, '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 0x10af5edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0efb20>, '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.KwQeDX2Oh6/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.VBwREEQ9rg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 07:16: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:16: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 mozsystemmonitor==0.3 07:16:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwQeDX2Oh6/Render', 07:16:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:51 INFO - 'HOME': '/Users/cltbld', 07:16:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:51 INFO - 'LOGNAME': 'cltbld', 07:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:51 INFO - 'MOZ_NO_REMOTE': '1', 07:16:51 INFO - 'NO_EM_RESTART': '1', 07:16:51 INFO - 'PAGER': '/bin/cat', 07:16:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:51 INFO - 'PWD': '/builds/slave/test', 07:16:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:51 INFO - 'SHELL': '/bin/bash', 07:16:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBwREEQ9rg/Listeners', 07:16:51 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 07:16:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:51 INFO - 'USER': 'cltbld', 07:16:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:51 INFO - 'XPC_FLAGS': '0x0', 07:16:51 INFO - 'XPC_SERVICE_NAME': '0', 07:16:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:16: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 07:16: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 07:16: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 07:16: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 07:16: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 07:16: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 07:16:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:16:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:16:53 INFO - Installing collected packages: mozsystemmonitor 07:16:53 INFO - Running setup.py install for mozsystemmonitor 07:16:54 INFO - Successfully installed mozsystemmonitor 07:16:54 INFO - Cleaning up... 07:16:54 INFO - Return code: 0 07:16:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:16: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')]} 07:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16: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')]} 07:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b2e13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b257570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b0d6d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f7d30>, '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 0x10af5edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0efb20>, '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.KwQeDX2Oh6/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.VBwREEQ9rg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 07:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:16:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwQeDX2Oh6/Render', 07:16:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:54 INFO - 'HOME': '/Users/cltbld', 07:16:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:54 INFO - 'LOGNAME': 'cltbld', 07:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:54 INFO - 'MOZ_NO_REMOTE': '1', 07:16:54 INFO - 'NO_EM_RESTART': '1', 07:16:54 INFO - 'PAGER': '/bin/cat', 07:16:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:54 INFO - 'PWD': '/builds/slave/test', 07:16:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:54 INFO - 'SHELL': '/bin/bash', 07:16:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBwREEQ9rg/Listeners', 07:16:54 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 07:16:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:54 INFO - 'USER': 'cltbld', 07:16:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:54 INFO - 'XPC_FLAGS': '0x0', 07:16:54 INFO - 'XPC_SERVICE_NAME': '0', 07:16:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:54 INFO - Downloading/unpacking jsonschema==2.5.1 07:16: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 07:16: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 07:16: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 07:16: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 07:16: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 07:16: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 07:16:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:16:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:16:56 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 07:16:56 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 07:16:56 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 07:16:56 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 07:16:56 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 07:16:56 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 07:16:57 INFO - Downloading functools32-3.2.3-2.tar.gz 07:16:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:16:57 INFO - warning: no files found matching '*.txt' 07:16:57 INFO - no previously-included directories found matching 'build' 07:16:57 INFO - no previously-included directories found matching 'dist' 07:16:57 INFO - no previously-included directories found matching '.git*' 07:16:57 INFO - Installing collected packages: jsonschema, functools32 07:16:57 INFO - Running setup.py install for functools32 07:16:57 INFO - warning: no files found matching '*.txt' 07:16:57 INFO - no previously-included directories found matching 'build' 07:16:57 INFO - no previously-included directories found matching 'dist' 07:16:57 INFO - no previously-included directories found matching '.git*' 07:16:57 INFO - Successfully installed jsonschema functools32 07:16:57 INFO - Cleaning up... 07:16:57 INFO - Return code: 0 07:16:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:16:57 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')]} 07:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:57 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')]} 07:16:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:57 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 0x10b2e13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b257570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b0d6d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f7d30>, '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 0x10af5edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0efb20>, '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.KwQeDX2Oh6/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.VBwREEQ9rg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 07:16:57 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 07:16:57 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 07:16:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwQeDX2Oh6/Render', 07:16:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:57 INFO - 'HOME': '/Users/cltbld', 07:16:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:57 INFO - 'LOGNAME': 'cltbld', 07:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:57 INFO - 'MOZ_NO_REMOTE': '1', 07:16:57 INFO - 'NO_EM_RESTART': '1', 07:16:57 INFO - 'PAGER': '/bin/cat', 07:16:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:57 INFO - 'PWD': '/builds/slave/test', 07:16:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:57 INFO - 'SHELL': '/bin/bash', 07:16:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBwREEQ9rg/Listeners', 07:16:57 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 07:16:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:57 INFO - 'USER': 'cltbld', 07:16:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:57 INFO - 'XPC_FLAGS': '0x0', 07:16:57 INFO - 'XPC_SERVICE_NAME': '0', 07:16:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:16:58 INFO - Cleaning up... 07:16:58 INFO - Return code: 0 07:16:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:16:58 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')]} 07:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:58 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')]} 07:16:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:58 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 0x10b2e13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b257570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b0d6d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f7d30>, '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 0x10af5edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0efb20>, '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.KwQeDX2Oh6/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.VBwREEQ9rg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 07:16:58 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 07:16:58 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 07:16:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwQeDX2Oh6/Render', 07:16:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:58 INFO - 'HOME': '/Users/cltbld', 07:16:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:58 INFO - 'LOGNAME': 'cltbld', 07:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:58 INFO - 'MOZ_NO_REMOTE': '1', 07:16:58 INFO - 'NO_EM_RESTART': '1', 07:16:58 INFO - 'PAGER': '/bin/cat', 07:16:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:58 INFO - 'PWD': '/builds/slave/test', 07:16:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:58 INFO - 'SHELL': '/bin/bash', 07:16:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBwREEQ9rg/Listeners', 07:16:58 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 07:16:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:58 INFO - 'USER': 'cltbld', 07:16:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:58 INFO - 'XPC_FLAGS': '0x0', 07:16:58 INFO - 'XPC_SERVICE_NAME': '0', 07:16:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:58 INFO - Downloading/unpacking blobuploader==1.2.4 07:16:58 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 07:16:58 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 07:16:58 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 07:16:58 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 07:16:58 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 07:16:58 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 07:17:00 INFO - Downloading blobuploader-1.2.4.tar.gz 07:17:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:17:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:17:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:17:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:17:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:17:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:17:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:17:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:17:01 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 07:17:01 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 07:17:01 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 07:17:01 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 07:17:01 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 07:17:01 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 07:17:01 INFO - Downloading docopt-0.6.1.tar.gz 07:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:17:02 INFO - Installing collected packages: blobuploader, requests, docopt 07:17:02 INFO - Running setup.py install for blobuploader 07:17:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:17:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:17:02 INFO - Running setup.py install for requests 07:17:02 INFO - Running setup.py install for docopt 07:17:02 INFO - Successfully installed blobuploader requests docopt 07:17:02 INFO - Cleaning up... 07:17:02 INFO - Return code: 0 07:17:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:17:02 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')]} 07:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:17:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:17:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:17:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:17:03 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 0x10b2e13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b257570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b0d6d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f7d30>, '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 0x10af5edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0efb20>, '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.KwQeDX2Oh6/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.VBwREEQ9rg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 07:17:03 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 07:17:03 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 07:17:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwQeDX2Oh6/Render', 07:17:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:17:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:17:03 INFO - 'HOME': '/Users/cltbld', 07:17:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:17:03 INFO - 'LOGNAME': 'cltbld', 07:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:17:03 INFO - 'MOZ_NO_REMOTE': '1', 07:17:03 INFO - 'NO_EM_RESTART': '1', 07:17:03 INFO - 'PAGER': '/bin/cat', 07:17:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:17:03 INFO - 'PWD': '/builds/slave/test', 07:17:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:17:03 INFO - 'SHELL': '/bin/bash', 07:17:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBwREEQ9rg/Listeners', 07:17:03 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 07:17:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:17:03 INFO - 'USER': 'cltbld', 07:17:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:17:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:17:03 INFO - 'XPC_FLAGS': '0x0', 07:17:03 INFO - 'XPC_SERVICE_NAME': '0', 07:17:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:17:03 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-fucWS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:17:03 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-S2pbmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:17:03 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-AGGkOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:17:03 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-XNtvbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:17:03 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-vydq27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:17:03 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-S0O_4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:17:03 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-A9pel_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:17:04 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-AKd6UI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:17:04 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-4i9Jmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:17:04 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-7qY5gG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:17:04 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-859_aO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:17:04 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-pkr5dK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:17:04 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-yyL478-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:17:04 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-ofm__w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:17:04 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-miD3Yy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:17:04 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-4JhaAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:17:04 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-HkAQQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:17:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:17:05 INFO - Running setup.py install for manifestparser 07:17:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:17:05 INFO - Running setup.py install for mozcrash 07:17:05 INFO - Running setup.py install for mozdebug 07:17:05 INFO - Running setup.py install for mozdevice 07:17:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:17:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:17:05 INFO - Running setup.py install for mozfile 07:17:05 INFO - Running setup.py install for mozhttpd 07:17:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:17:05 INFO - Running setup.py install for mozinfo 07:17:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:17:06 INFO - Running setup.py install for mozInstall 07:17:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:17:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:17:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:17:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:17:06 INFO - Running setup.py install for mozleak 07:17:06 INFO - Running setup.py install for mozlog 07:17:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:17:06 INFO - Running setup.py install for moznetwork 07:17:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:17:06 INFO - Running setup.py install for mozprocess 07:17:06 INFO - Running setup.py install for mozprofile 07:17:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:17:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:17:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:17:07 INFO - Running setup.py install for mozrunner 07:17:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:17:07 INFO - Running setup.py install for mozscreenshot 07:17:07 INFO - Running setup.py install for moztest 07:17:07 INFO - Running setup.py install for mozversion 07:17:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:17:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:17:07 INFO - Cleaning up... 07:17:07 INFO - Return code: 0 07:17:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:17:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:17:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:17:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:17:07 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 0x10b2e13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b257570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b0d6d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f7d30>, '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 0x10af5edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0efb20>, '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.KwQeDX2Oh6/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.VBwREEQ9rg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 07:17:07 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 07:17:07 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 07:17:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwQeDX2Oh6/Render', 07:17:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:17:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:17:07 INFO - 'HOME': '/Users/cltbld', 07:17:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:17:07 INFO - 'LOGNAME': 'cltbld', 07:17:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:17:07 INFO - 'MOZ_NO_REMOTE': '1', 07:17:07 INFO - 'NO_EM_RESTART': '1', 07:17:07 INFO - 'PAGER': '/bin/cat', 07:17:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:17:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:17:07 INFO - 'PWD': '/builds/slave/test', 07:17:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:17:07 INFO - 'SHELL': '/bin/bash', 07:17:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBwREEQ9rg/Listeners', 07:17:07 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 07:17:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:17:07 INFO - 'USER': 'cltbld', 07:17:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:17:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:17:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:17:07 INFO - 'XPC_FLAGS': '0x0', 07:17:07 INFO - 'XPC_SERVICE_NAME': '0', 07:17:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:17:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:17:07 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-zoUYP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:17:08 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)) 07:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:17:08 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-Cw9k2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:17:08 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)) 07:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:17:08 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-knJwRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:17:08 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)) 07:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:17:08 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-BQg5Fg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:17:08 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)) 07:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:17:08 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-l7M7uy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:17:08 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)) 07:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:17:08 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-5nE2WB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:17:08 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)) 07:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:17:08 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-EXjmbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:17:08 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)) 07:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:17:08 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-trO1K1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:17:08 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)) 07:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:17:08 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-Wkakli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:17:08 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)) 07:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:17:08 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-3Id1OG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:17:08 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)) 07:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:17:08 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-4RW1Y3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:17:09 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)) 07:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:17:09 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-i5XAWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:17:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:17:09 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-1CZKPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:17:09 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)) 07:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:17:09 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-2ZqQLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:17:09 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)) 07:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:17:09 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-lQ5pTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:17:09 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)) 07:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:17:09 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-39SgAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:17:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:17:09 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-h8AS8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:17:09 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)) 07:17:09 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)) 07:17:09 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)) 07:17:09 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)) 07:17:09 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)) 07:17:09 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)) 07:17:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:17:09 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 07:17:09 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 07:17:09 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 07:17:09 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 07:17:09 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 07:17:09 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 07:17:12 INFO - Downloading blessings-1.6.tar.gz 07:17:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:17:12 INFO - Installing collected packages: blessings 07:17:12 INFO - Running setup.py install for blessings 07:17:12 INFO - Successfully installed blessings 07:17:12 INFO - Cleaning up... 07:17:12 INFO - Return code: 0 07:17:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:17:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:17:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:17:12 INFO - Reading from file tmpfile_stdout 07:17:12 INFO - Current package versions: 07:17:12 INFO - blessings == 1.6 07:17:12 INFO - blobuploader == 1.2.4 07:17:12 INFO - docopt == 0.6.1 07:17:12 INFO - functools32 == 3.2.3-2 07:17:12 INFO - jsonschema == 2.5.1 07:17:12 INFO - manifestparser == 1.1 07:17:12 INFO - mozInstall == 1.12 07:17:12 INFO - mozcrash == 1.0 07:17:12 INFO - mozdebug == 0.1 07:17:12 INFO - mozdevice == 0.49 07:17:12 INFO - mozfile == 1.2 07:17:12 INFO - mozhttpd == 0.7 07:17:12 INFO - mozinfo == 0.9 07:17:12 INFO - mozleak == 0.1 07:17:12 INFO - mozlog == 3.4 07:17:12 INFO - moznetwork == 0.27 07:17:12 INFO - mozprocess == 0.23 07:17:12 INFO - mozprofile == 0.28 07:17:12 INFO - mozrunner == 6.13 07:17:12 INFO - mozscreenshot == 0.1 07:17:12 INFO - mozsystemmonitor == 0.3 07:17:12 INFO - moztest == 0.7 07:17:12 INFO - mozversion == 1.4 07:17:12 INFO - psutil == 3.1.1 07:17:12 INFO - requests == 1.2.3 07:17:12 INFO - wsgiref == 0.1.2 07:17:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:17:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:17:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:17:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:17:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:17:12 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 0x10b2e13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b257570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b0d6d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f7d30>, '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 0x10af5edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0efb20>, '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.KwQeDX2Oh6/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.VBwREEQ9rg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 07:17:12 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 07:17:12 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 07:17:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwQeDX2Oh6/Render', 07:17:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:17:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:17:12 INFO - 'HOME': '/Users/cltbld', 07:17:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:17:12 INFO - 'LOGNAME': 'cltbld', 07:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:17:12 INFO - 'MOZ_NO_REMOTE': '1', 07:17:12 INFO - 'NO_EM_RESTART': '1', 07:17:12 INFO - 'PAGER': '/bin/cat', 07:17:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:17:12 INFO - 'PWD': '/builds/slave/test', 07:17:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:17:12 INFO - 'SHELL': '/bin/bash', 07:17:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBwREEQ9rg/Listeners', 07:17:12 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 07:17:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:17:12 INFO - 'USER': 'cltbld', 07:17:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:17:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:17:12 INFO - 'XPC_FLAGS': '0x0', 07:17:12 INFO - 'XPC_SERVICE_NAME': '0', 07:17:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:17:13 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)) 07:17:13 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)) 07:17:13 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)) 07:17:13 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)) 07:17:13 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)) 07:17:13 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)) 07:17:13 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)) 07:17:13 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)) 07:17:13 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)) 07:17:13 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)) 07:17:13 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)) 07:17:13 INFO - Cleaning up... 07:17:13 INFO - Return code: 0 07:17:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:17:13 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')]} 07:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:17:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:17:13 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')]} 07:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:17:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:17:13 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 0x10b2e13f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b257570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2b0d6d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f7d30>, '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 0x10af5edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0efb20>, '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.KwQeDX2Oh6/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.VBwREEQ9rg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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 07:17:13 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 07:17:13 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 07:17:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwQeDX2Oh6/Render', 07:17:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:17:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:17:13 INFO - 'HOME': '/Users/cltbld', 07:17:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:17:13 INFO - 'LOGNAME': 'cltbld', 07:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:17:13 INFO - 'MOZ_NO_REMOTE': '1', 07:17:13 INFO - 'NO_EM_RESTART': '1', 07:17:13 INFO - 'PAGER': '/bin/cat', 07:17:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:17:13 INFO - 'PWD': '/builds/slave/test', 07:17:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:17:13 INFO - 'SHELL': '/bin/bash', 07:17:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBwREEQ9rg/Listeners', 07:17:13 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 07:17:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:17:13 INFO - 'USER': 'cltbld', 07:17:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:17:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:17:13 INFO - 'XPC_FLAGS': '0x0', 07:17:13 INFO - 'XPC_SERVICE_NAME': '0', 07:17:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:17:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:17:13 INFO - Cleaning up... 07:17:13 INFO - Return code: 0 07:17:13 INFO - Running post-action listener: _resource_record_post_action 07:17:13 INFO - Running post-action listener: _start_resource_monitoring 07:17:13 INFO - Starting resource monitoring. 07:17:13 INFO - [mozharness: 2016-11-20 15:17:13.427849Z] Finished create-virtualenv step (success) 07:17:13 INFO - [mozharness: 2016-11-20 15:17:13.428848Z] Running install step. 07:17:13 INFO - Running pre-action listener: _resource_record_pre_action 07:17:13 INFO - Running main action method: install 07:17:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:17:13 INFO - Reading from file tmpfile_stdout 07:17:13 INFO - Detecting whether we're running mozinstall >=1.0... 07:17:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:17:13 INFO - Reading from file tmpfile_stdout 07:17:13 INFO - Output received: 07:17:13 INFO - Usage: mozinstall [options] installer 07:17:13 INFO - Options: 07:17:13 INFO - -h, --help show this help message and exit 07:17:13 INFO - -d DEST, --destination=DEST 07:17:13 INFO - Directory to install application into. [default: 07:17:13 INFO - "/builds/slave/test"] 07:17:13 INFO - --app=APP Application being installed. [default: firefox] 07:17:13 INFO - mkdir: /builds/slave/test/build/application 07:17:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:17:46 INFO - Reading from file tmpfile_stdout 07:17:46 INFO - Output received: 07:17:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:17:46 INFO - Running post-action listener: _resource_record_post_action 07:17:46 INFO - [mozharness: 2016-11-20 15:17:46.964713Z] Finished install step (success) 07:17:46 INFO - [mozharness: 2016-11-20 15:17:46.964838Z] Running run-tests step. 07:17:46 INFO - Running pre-action listener: _resource_record_pre_action 07:17:46 INFO - Running main action method: run_tests 07:17:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:17:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:17:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:17:46 INFO - 2016-11-20 07:17:46.979 screenresolution[1636:7754] starting screenresolution argv=screenresolution get 07:17:46 INFO - 2016-11-20 07:17:46.981 screenresolution[1636:7754] Display 0: 1600x1200x32@60 07:17:46 INFO - 2016-11-20 07:17:46.987 screenresolution[1637:7756] starting screenresolution argv=screenresolution list 07:17:46 INFO - Available Modes on Display 0 07:17:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:17:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:17:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:17:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:17:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:17:47 INFO - Intel Iris: 07:17:47 INFO - Chipset Model: Intel Iris 07:17:47 INFO - Type: GPU 07:17:47 INFO - Bus: Built-In 07:17:47 INFO - VRAM (Dynamic, Max): 1536 MB 07:17:47 INFO - Vendor: Intel (0x8086) 07:17:47 INFO - Device ID: 0x0a2e 07:17:47 INFO - Revision ID: 0x0009 07:17:47 INFO - Displays: 07:17:47 INFO - Display: 07:17:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:17:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:17:47 INFO - Display Serial Number: 1600x1200 60 07:17:47 INFO - Main Display: Yes 07:17:47 INFO - Mirror: Off 07:17:47 INFO - Online: Yes 07:17:47 INFO - Rotation: Supported 07:17:47 INFO - Return code: 0 07:17:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:17:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:17:47 INFO - Python 2.7.3 07:17:47 INFO - Return code: 0 07:17:47 INFO - grabbing minidump binary from tooltool 07:17: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')]} 07:17:47 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 0x10b0f7d30>, '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 0x10af5edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0efb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:17:47 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 07:17:47 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 07:17:47 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 07:17:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:17:47 INFO - Return code: 0 07:17:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:17:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:17:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:17:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:17:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:17:47 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/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0310', '--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 07:17:47 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/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0310 --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 07:17:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwQeDX2Oh6/Render', 07:17:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:17:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:17:47 INFO - 'HOME': '/Users/cltbld', 07:17:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:17:47 INFO - 'LOGNAME': 'cltbld', 07:17:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:17:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:17:47 INFO - 'MOZ_NO_REMOTE': '1', 07:17:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:17:47 INFO - 'NO_EM_RESTART': '1', 07:17:47 INFO - 'PAGER': '/bin/cat', 07:17:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:17:47 INFO - 'PWD': '/builds/slave/test', 07:17:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:17:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:17:47 INFO - 'SHELL': '/bin/bash', 07:17:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBwREEQ9rg/Listeners', 07:17:47 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 07:17:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:17:47 INFO - 'USER': 'cltbld', 07:17:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:17:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:17:47 INFO - 'XPC_FLAGS': '0x0', 07:17:47 INFO - 'XPC_SERVICE_NAME': '0', 07:17:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:17:47 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/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0310', '--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 07:17:47 INFO - mozversion application_buildid: 20161120061422 07:17:47 INFO - mozversion application_changeset: b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 07:17:47 INFO - mozversion application_display_name: Nightly 07:17:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:17:47 INFO - mozversion application_name: Firefox 07:17:47 INFO - mozversion application_remotingname: firefox 07:17:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:17:47 INFO - mozversion application_vendor: Mozilla 07:17:47 INFO - mozversion application_version: 53.0a1 07:17:47 INFO - mozversion platform_buildid: 20161120061422 07:17:47 INFO - mozversion platform_changeset: b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 07:17:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:17:47 INFO - mozversion platform_version: 53.0a1 07:17:47 INFO - using testdate: 1479655067 07:17:47 INFO - actual date: 1479655067 07:17:47 INFO - starting webserver on 'localhost:49242' 07:17:47 INFO - SUITE-START | Running 2 tests 07:17:47 INFO - TEST-START | dromaeo_css 07:17:47 INFO - operating with platform_type : mac_ 07:17:47 INFO - Initialising browser for dromaeo_css test... 07:17:47 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmpI73BXQ/profile http://localhost:49242/getInfo.html) 07:17:50 INFO - PROCESS | 1647 | 2016-11-20 07:17:50.901 plugin-container[1649:7922] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.tsm.portname' 07:17:50 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:17:50 INFO - PROCESS | 1647 | 2016-11-20 07:17:50.904 plugin-container[1649:7922] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b1f, name = 'com.apple.CFPasteboardClient' 07:17:50 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:17:50 INFO - PROCESS | 1647 | 2016-11-20 07:17:50.904 plugin-container[1649:7922] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:17:51 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 07:17:51 INFO - PROCESS | 1647 | colorDepth:24 07:17:51 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 07:17:51 INFO - PROCESS | 1647 | __metrics 07:17:51 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 07:17:51 INFO - TEST-INFO | 1647: exit 0 07:17:51 INFO - Browser initialized. 07:17:51 INFO - Running cycle 1/1 for dromaeo_css test... 07:17:51 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmpI73BXQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:17:54 INFO - PROCESS | 1650 | 2016-11-20 07:17:54.653 plugin-container[1651:8041] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 07:17:54 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:17:54 INFO - PROCESS | 1650 | 2016-11-20 07:17:54.654 plugin-container[1651:8041] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4723, name = 'com.apple.CFPasteboardClient' 07:17:54 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:17:54 INFO - PROCESS | 1650 | 2016-11-20 07:17:54.654 plugin-container[1651:8041] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:18:04 INFO - PROCESS | 1650 | RSS: Main: 242995200 07:18:04 INFO - PROCESS | 1650 | 07:18:53 INFO - PROCESS | 1650 | 1479655133987 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:21:07 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 07:21:09 INFO - PROCESS | 1650 | RSS: Main: 248721408 07:21:09 INFO - PROCESS | 1650 | 07:24:12 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 07:24:12 INFO - PROCESS | 1650 | RSS: Main: 259809280 07:24:12 INFO - PROCESS | 1650 | 07:27:14 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 07:27:15 INFO - PROCESS | 1650 | RSS: Main: 264171520 07:27:15 INFO - PROCESS | 1650 | 07:30:17 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 07:30:17 INFO - PROCESS | 1650 | RSS: Main: 262873088 07:30:17 INFO - PROCESS | 1650 | 07:33:20 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 07:33:21 INFO - PROCESS | 1650 | RSS: Main: 267235328 07:33:21 INFO - PROCESS | 1650 | 07:36:24 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 07:36:24 INFO - PROCESS | 1650 | RSS: Main: 269959168 07:36:24 INFO - PROCESS | 1650 | 07:36:24 INFO - PROCESS | 1650 | __start_tp_report 07:36:24 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 07:36:24 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 07:36:24 INFO - PROCESS | 1650 | |i|pagename|runs| 07:36:24 INFO - PROCESS | 1650 | |0;dojo.html;3078.5843911072006;3347.715444187244;3366.20894089889;3388.593368795745;3393.7963722176673;4390;5607.943920560793;5692.544596432286;5773.566982476315;5824.271966004249;4995.5762607656825;5021.246812978065;5627.971860140694;5692.345380281269;5795.21764561784;5085.262636917645;5158.638895277332;5259.132243179871;5284.392294886099;5292.603054770889;5095.490450954893;5279.419263880989;5315.7873685052555;5335.7332133393365;5350.973245133789;5055.6713813602;5059.038782631288;5144.9742751286;5182.844514664566;5227.529522343008;5116;5194.766235519411;5231.764570594332;5251.474852514756;5252.606054545924;5106.08090543702;5119.411267704225;5139.383274007143;5142.61430392722;5187.066328060948;3980.641742243212;4194.622483976426;5174.379074511083;5503.724813759336;5600.719964001783;5224.39919409269;6103.61042559425;6125.2049292651645;6137.928623859655;6204.379562043805;6219.906701399483;6234.812955611339;6239.188905442309;6241.531885108589;6270.592411493239;5348.9732551337775;5765.07758758605;5803.564732645069;5814.156947242626;5878.853028674317;6136.028952951454;6158.969205153946;6160.630362178194;6171;6210.285817130997;6058;6105.035213715927;6123.540734444934;6136.151476879544;6148.600340977822;6192.287886892975;6202.28673702521;6213.130161777355;6213.689315534205;6226.688665566794;6012.699365031731;6076.5442591805795;6080.209572755513;6115.113308570232;6132.478739307209;6101.671357587133;6151.200343955346;6154.5384096192065;6157.476614487729;6245.469135123474;4170.22850772608;4901.019796040806;4921.114199444134;4928.926066109011;4954.801807927643;4382.247101159562;4396.142752163361;4415.66882483815;4426.026274219666;4460.576245256695;4683.718976861399;5533.750981205855;5750.166225897222;5768.711564421762;5810.477057064884;6133.885575987479;6180.505559555225;6215.782447614402;6231.376862313823;6241.500679945593;5281.630285879952;5872.853178670567;5876.7355469004;5900.881982360329;5903.557233207441;5257.737113144328;5261.447548007481;5407.351117865881;5420.457954204548;5425;6193.194884664965;6197.977333739883;6230;6238.282597501255;6251.281102673069;6191.916414627332;6219.160413344322;6232.407921247474;6235.189425374672;6235.594686345373;6270.278917924315;6293.842653933688;6300.149479820166;6321.589096708699;6341.112244285711;6046.939530604637;6101.60339577926;6128.264608247039;6182.752689892354;6190.473809726223;6146.416090471398;6234;6246.562740608114;6268;6283.277423096402;5284.5995811109315;5981.102834574848;5989.431004054608;6150.138980542817;6156.445919867234;5787.7395517200985;5857.648541087548;6020.819375418744;6131.662758548145;6158.230221222348;4905.852824415273;5187.832737634002;5559.861003474945;5701.287339082614;5809.593328466966;4166.354215096665;4773.902002539366;5018.673786203885;5136.332276803992;5938.287405511366;5866.090755932837;5891.49922256613;5928.407159284209;6037.275526936795;6042.516598672008 07:36:24 INFO - PROCESS | 1650 | |1;ext.html;449.707690001499;472.3575936726053;541.5261646059698;556.6131538580688;565.5334349161938;36036.459453108226;37050.88847334581;39247.21505569891;39327.803360983155;39841.0039749006;39615.60384396155;40253.7987310063;40291.194176116536;40291.79854100733;40313.59686403135;9177.495237761921;9381.437113773196;9426.905730942706;9433.76415589609;9438.386504877195;9285.767855803626;9314.347995640308;9388.953055234715;9407.81184376312;9436.292278079138;9301.581428835685;9392.765180870465;9440.575174117146;9441.669541566047;9447.81104377912;9274.48990305533;9376.249900008017;9420.340576159671;9421.905780942172;9444.244460443217;7957.522548647042;9180.265578753684;9231.723048308562;9293.953530232373;9325.533723313807;36793.816030919676;37081.81459092714;37422.8128859354;37475.62524374748;37487.81256093702;3457.6888080072663;3825.5791862895057;3915.0799562102884;3924.0974575847713;3959;26797.73202267972;29366.265843353875;29794.808207671667;29841.25396865074;30549.694503054907;3289.6874796894494;3306.718928891047;3313.1054615253747;3349.6482869298616;3349.6650334966553;464748.67625661654;465786;470855.6457217726;470865;472578;57817.710911445174;58275.41724582785;58276;58383.41616583865;58392;60504;60783.69608151931;61071;61149.77700445966;61300.38699613036;15129.940904136605;15285.006474579339;15356.462523811613;15366.84633153654;15390;11834.289942603471;11880.762384752257;11885.049196064467;11908;11925.761484770255;12757.617271481871;16205.270762815702;16213.756793648105;16257.18714064292;16257.26842292076;5417.83746487606;6615.6030638161865;6662;6723.697433615499;6742.224644165885;801.3669201330883;943.2218419803552;953.7043516509904;983.1692220074026;1009.0817356205818;27915.16254512368;28391;29513.409731805245;29683.109506714834;30022.699773002427;955.6320816485703;1020.811149937264;1069.4759567811873;1123.2361993844265;1137.6359564939305;15001;15025.849741502663;15145.091294522364;15187.772183417257;15406.075635461908;609129;640243;642941;643118;645571;53343.19985200225;56083;56100.43899560952;56769;56770.14844777332;1145.616218566773;1237.8452693413144;1315.1780137414116;1328.103530117167;1358.456617353047;24987.50024999526;25667.486650266517;26770;29988.850055749583;30773.692263077082;48241.75879120582;48379;48398.790030249525;48718.75640621916;49039.75480122577;430854;431255;431942;432518;434803;25268.989240430175;25459.872700636377;25538;25704.228873133838;25722.228333150037;44332.33501497415;44688.77655611701;44728.658140255844;44863.32705009362;44924.326135108655;38028.42957355586;38230.044248894;38855.834324970296;38910.41634375543;39178.80410597929;36833;37123.81438092792;37488;38322;38530;36770.63229367672;37187.81406092952;37702.62297377101;37712.24575508529;39027.41458878176;31193.532097019015;33663.15842103967;34488.31023379569;34526.13684657904;34530.482042768876;20021.599568008267;25099.74900251047;25194.37014074663;25404.872975635004;25811.483770324863 07:36:24 INFO - PROCESS | 1650 | |2;jquery.html;332.5776264144535;353.23877044968094;365.7549441873944;379.9449079883416;400.96190861868115;17799.466016019524;18262.634747305063;19260.229590816387;19395.22419103236;19566.510837229078;18924.526886827836;18945.526361840963;19077.618447631037;19163.041847907618;19235.42293731187;5579.414161513034;5852.531797456193;5875;5880.147378630096;5883.558733095035;5527.585431092684;5750.712464376786;5781.855453613652;5793.9281232971825;5795.304563452391;5607;5698.173764804101;5723.284589426322;5724.513416359614;5746.655200687972;5458.235846981426;5742.885142297131;5758.82723518293;5826.184334193216;5877.882442351171;5464.535514481274;5612.775488980476;5655.462731040586;5677.091665333568;5687.431256874321;12376.319302438362;12597.748045039049;12620.116591838574;12755.808662870064;14006.509772158028;2058.4133521946173;2137.7542130286574;2156.7196264485515;2173.608750424923;2484.416162201881;8669.61286194206;9371.76570585734;9462.716118516455;10550.525226364833;14175.007749457542;1598.8081430228447;1744.877858549902;1796.8921864688161;1897.6489349470392;1950.1224448998007;110723;119136.21295680485;119783;120292;122312.38843805813;10737.355758654505;11146;11250.606228782117;11339.886601134049;11730.648080557597;14083.647908802363;14093.083949543452;14144.434222631186;14155.575332740034;14170.64573385653;7932.286094251545;8055.838883222303;8119.5534245617055;8154.2253485918745;8180.918190818015;7991.2807847294025;7994.760157195294;8004.919950800418;8079.878801817978;8150.3479721622125;3766.851110411296;4302.247106756305;4343.1747967886;4392.275274579659;4402.295632698753;1875.7842848072373;1922.4617107209724;1922.6058657975082;1929.5465565592074;1929.556202073531;745.6309126982178;795.3200013988072;848.2365870716404;939.5302348825587;962.9277804164716;16798.076105814296;18922.24311027571;19089.04554772256;19917;20280.505745298527;864.1962974433837;916.5417291354323;942.3874481587023;954.4464210757883;963.21016766251;7703.8844417333785;9706.835179778473;10111.393316400892;10575.894241057646;10601.946990265;485397;516204;571791;577132;587296.5716542766;106587.46706266575;109203.45398272958;112286.43856780663;126955;137166;783.3106865958034;945.1352012908112;1000.9999999999854;1003.4280460137652;1007.7480629842539;17129.97220166794;17338.93501516867;17414.73877891858;17525;17618.73571896397;41538;52204;52899;53574.46425535695;54388.45611543992;65770;67854.50036994726;70270.64864675644;76163.61918190373;76335.61832190804;6719.798406047827;7059.2940705928995;7122.893156602759;7184.137903451619;7325.413966882743;9192.310576706908;10663.360198388122;12218.652725676346;12502.249865008129;12664.493420263087;8687.435316704394;9992.400455972665;10632.787344253225;10638.574457021632;10767.800557782315;10428.374297542172;10516;10689.465526723478;10727.463626818471;10872.61945831892;10614.257002009785;11169.888301116885;11215.046721028804;11296.378699171624;11300.09599232043;7692.192319806563;7843.921560784319;8033.277005069572;8106.837863242584;8181.918180818116;9017.458952462612;10615;10861.239713219999;10885.782284354427;11047.834282485608 07:36:24 INFO - PROCESS | 1650 | |3;mootools.html;1318.2947123289039;1448.3627204030226;1598.2408356030887;1776.2362184260762;1795.237024264688;7146.821329466759;7487.401007919367;7758.456908016441;8159.469634473755;8515.701950431736;7931.246531579505;8225.054118776343;8259.132791056943;8363.581820908961;8375.874361884577;2286.2798218561184;2324.2213858357386;2329.2546385156757;2331.1258278145697;2342.1334106380664;2304.608216603173;2341.1103780563362;2350.471143992605;2351.235848349285;2364.243442098529;2261.7738226177416;2279.225063478417;2292.0373443153917;2292.255017119443;2292.4727312718087;2215.2246713650093;2237.19460994042;2256.593813113639;2267.4104732769515;2268.9546209075725;1809.4209852847093;1928.2865339824355;1956.9217231310731;2159.75162856272;2323.9883800580888;8297.460665056751;8323.001239851195;8349.874751878726;8360.83278334436;8377.706780262723;4271.63691086258;4371.868843934687;4592.242280023792;5025.422076461181;5175.922361164586;3097.3805238952073;3241.3679332530164;3350.5141754445713;3454.5854497460464;3561.6260292669413;1038.086483894176;1072.1529991306859;1082.658962426833;1100.3452945497415;1105.2981356838466;9181.724548263563;10261.79476410475;10394.376337419704;10503.422311772849;10524.789504209873;4454.5990860822685;4574.153781550423;4695.577398034125;4707.7175369477945;4753.31076993834;9360;9403.200727938201;9422.340436169403;9426.104520070583;9447.779771753632;5176.301199338116;5191;5197.636165468381;5202.505761952609;5206.375234971828;4242.787860606957;4265.466816647919;4682.297655351725;4740.241561350167;4766.642501812378;3180.268538236219;3607.6753092221825;3690;3743.3636281832155;3765.9246815063543;1271.4786937355825;1279.6736832107938;1280.5582074184392;1290.180735233115;1299.3698056442618;818.3575892924026;851.314691673209;865.2731705367396;879.6437442835662;931.1619542411748;5127.79488820443;5482.479164479368;5531.087370583889;5733.799317023884;5797.449242321973;415.8399016378655;423.6737711961772;431.939528466015;436.32587652076984;439.5340938605089;3729.9813500932323;3745.6067112952746;3848.2688289224957;3861.131245469803;3895.1235971906094;9747.56135973883;9965.10314071737;10105.898941010642;10119.494025298705;10133.2906696532;8634.266087382517;9875.755975569677;9889.50552472388;10064.194864410829;10084.899151008396;6100.23747031621;7098.148222213367;7592.544447333067;7835.0989636191425;7848.529088254723;4968.925466118083;5304.9469505306;5534.833954981357;5539.362973257966;5588;7811.359614481023;9204.033576474641;9271.907280927104;9309.348345615741;9346.766330841783;9006.59470323824;9371;9439.858402124111;9580.425174489274;9730.610775568897;6528.608283503005;6727.461803055841;6905.309469053054;7068.893966590608;7146.178189508273;6585.176852893388;7746.30283274508;7953.204679532;7956.2043795619975;7980.960095199487;6335.334789847;6821.761238356634;6998.440124790106;7124.857502850017;7319.670614822239;6881.41507971828;6929.5842249465195;6950.8609827804175;6961.129858767654;7025.086738723933;5677.545796336366;6534.248561415308;6560.442362399059;6690.364415380531;6722.798316050526;4779.330893674819;4825.5898248649255;4834.564889159992;4866.099771542276;4924.8768780780765;4659.20793465105;4725.173094708343;4879.682820616648;4905.3623029006;5005.524475174852 07:36:24 INFO - PROCESS | 1650 | |4;prototype.html;278.6725597423028;297.57892581996464;303.322075162013;309.1205520294762;313.3920194822045;4782.641301902354;5272.864489881595;5418.160185171295;5502.834914952553;5549.38956714761;4747.548982846624;5186.247994040871;5287.889543195924;5397.705974960646;5413.83758487246;3479.3389256041273;4108.116754897696;4126.6079722426475;4158.563350848162;4177.832886684529;3276.65657080597;3997.4603428537066;4082.8979275518054;4126.739875498352;4215.536291007987;3563.2339047104865;3813.9618603813883;4039.0508230565792;4112.691548133902;4122.752634841919;4110.6300432961025;4117.197146556423;4126.711130220886;4128.318827393477;4161.188568229197;3491.9650803491895;3577.481265216555;3627.220147668264;3644.7084233261276;3658.213484100931;5049.469805670426;5180.6891586504935;5278.2346559749;5313.920291195635;5351.260840226942;4142.71001029928;4395.296752519614;4777.952220477786;4796.088743138793;4818;4944.505549445063;5490.69801160936;5736.741846616912;5746.511546518551;5749.728857101463;3339.632640409553;3990.86031988879;4007.438958545806;4043.514778226602;4047.0489434982755;5249.737513124367;5319.760610772486;5996.19051428056;6052.636841789506;6075.6962151891785;3317.286783341545;3904.6095390460728;4029.5768944261017;4052.7163098583405;4092.856750013735;3747.0070431335716;4089.5073298245925;4137.234611596864;4137.668986481074;4150;4211.284081706117;4721.291806229093;4726.905461890743;4749.105733298662;4749.833755818529;4888.242322440027;5205.219217117463;5269.446708095671;5758.568107391962;5794.942050579524;1074.7313171707074;1281.398252184769;1296.7486375647545;1328.973420531584;1345.1705680274817;804.0994086623019;804.6821505505293;869.2220462685987;912.589334799343;936.8032713130319;4495.977520112444;4788.688735232199;4832.806687732522;4950.504949505021;5068.467810879804;4562.794674239601;4865.489123641966;5288.9735551322;5471.2887324647545;5516.393196748354;4637.46669133054;4684.859454216379;4932.93941802507;5092.719900405513;5121.156383701865;2480.6031035034307;2790.623265859083;2893.811902226391;2910.264351509574;2918.678945316056;5108.744562771846;5448.754806033738;5642.943570564242;5787.334456537552;5793.594448388657;5318.680879147263;5351.601732899637;5624.803131890446;5687.720262940805;5695.658260504383;4589.747563884019;5485.9725701371235;5710.71446427677;5757.798477053283;5790.681512516852;4575.702579332466;4742.454617718869;5565.972170139124;5574.470425309589;5606.859828504157;4654.6741728078705;4765.975315307225;5332.840014799563;5467.343918729778;5467.699276539828;5397.649152805055;5476.205950137288;5678.041075828833;5735.569832262514;5738.483536481736;3316.3864685033345;3510.9291666042104;3823.847046118124;3864.7294689372598;3880.3597406428753;4684.508126646653;4901.965684241;5420.83737487876;5615.820677657574;5644.810681964676;4577.8397756078375;5586.944130558642;5744.195812586157;5747.827565173052;5748.6838223898085;5691.686957217393;5713.200151978644;5722.656640601577;5727.341355743976;5797.797077102281;4712.468447754425;4842.080004799077;5426.70153141581;5567.3040869891265;5589.692566908697;4783.354247176726;5653.77734691222;5743.540516758566;5748.712564371848;5792.681402522901;4654.767261636973;5579.094633756468;5653.886922261614;5707.514861236841;5720.11338242573 07:36:24 INFO - PROCESS | 1650 | |5;yui.html;687.5153017122929;689.2659317826515;715.6493318274046;739.2865884421532;771.0901136658744;14322.212278324701;14876.107433553967;14913.478028269014;14922.701545969074;14928.25358732063;13494.595162145151;13633.795493067611;13666.931665341659;13676.52132175374;13680.452781888738;634.3719717479685;658.0556900847275;794.4637369775407;801.1707882341777;817.207308910359;671.318611609217;813.894193754811;819.4345901328095;825.0717942314895;825.331481499984;863.2144748279068;864.2394692670473;864.4553931023478;865.6234537975969;868.4875923205308;849.1847826086964;859.8710193471029;862.5859587398053;862.779991102268;863.9395242333039;612.3753771153443;624.5596854217765;635.8823617630753;636.4176778247897;640.810960766575;14293.356798943969;14546.054506457152;14682.412703491955;14734.18961957092;14878.181700006495;13604.659883502893;13697.178169309873;13810;13921.373538190806;13940.58178254654;11452.083833293316;11575.76848463026;11823.763524729457;11923.94038029813;12134.968527675159;3556.324298383299;3588.910277243064;3732.570754363229;3749.2876353492748;3758.68051215647;97605.51197244039;99038.50480747622;99102.50448747783;99226;99430.50284748603;10676.679699609023;10724.571017159225;10856;10870.510827012804;10959.917615653001;20611.660242084217;21365.78634213669;21401.250956216456;21449.142034318764;21452.927353632567;6625.370589793962;7677.155512893577;7683.270089341504;7711.383089352838;7778.922210777933;4224.683148763855;4243.533211346749;4277.828886844553;4303.160883627663;4397.428334316583;3285.3100848822014;3347.1799409144915;3387.1024178592693;3399.677030682131;3403.540522029544;611.8133969139366;623.5230049012509;633.3634697129355;643.9871202575922;645.8030300758314;587.6885250817073;611.7552978808512;658.720043981306;658.805652332563;666.4401902402005;8424.4945303282;8659.956700216584;8784.472931624123;8819.603117859711;9130.771730706785;632.5824955529329;651.3291309950758;651.3356376496042;677.4580335731396;694.1150033706963;4365.345198220229;4495.64034877209;4580.358749775034;4582.839600613963;4685.0629874025335;82682.58658706667;116706;120930;120976;124575;69188;92573.53713231391;106848;109218.53907304603;109848.90151098547;91396;94156;96163.51918240504;96232;96745;7141.000259963713;7588.506747061423;7941.483803552751;8689.391742578211;8733.30133589324;51882.481175187764;53478.17610094447;54189.916201674954;59877.80244395175;60288.69855650693;81894.77157842756;86259.56870215609;87137.1286287129;87773;87859.56070219607;11586.594469193538;11611.303321800719;11897.94051029774;12005.219660722025;12078;12177.269363838199;12201.328926909106;12260.570880019513;12658;12807.743845122859;11423.885761142283;11572.710682233012;11720.765584688428;11830.112741544246;12015.09886758514;11657.41712914334;11762.3530705812;11770.58802941893;11950.820737689115;12133.575324863587;11389.145814064144;11676.88323116792;11797.76404471923;11956.34240116802;12104.51581936748;9464.19554337889;9492.335536512377;9649.758756031155;9651.372660777028;9988.951160128372;8586.527740974056;9567;9822.65620703272;9848.212143028397;9877.703668889944 07:36:24 INFO - PROCESS | 1650 | __end_tp_report 07:36:24 INFO - PROCESS | 1650 | __start_cc_report 07:36:24 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,363 07:36:24 INFO - PROCESS | 1650 | __end_cc_report 07:36:24 INFO - PROCESS | 1650 | __startTimestamp1479656184755__endTimestamp 07:36:24 INFO - PROCESS | 1650 | 07:36:24 INFO - PROCESS | 1650 | ------- Summary: start ------- 07:36:24 INFO - PROCESS | 1650 | Number of tests: 6 07:36:24 INFO - PROCESS | 1650 | 07:36:24 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5610.85 Median:5857.65 stddev:696.67 (11.9%) stddev-sans-first:669.88 07:36:24 INFO - PROCESS | 1650 | Values: 3078.6 3347.7 3366.2 3388.6 3393.8 4390.0 5607.9 5692.5 5773.6 5824.3 4995.6 5021.2 5628.0 5692.3 5795.2 5085.3 5158.6 5259.1 5284.4 5292.6 5095.5 5279.4 5315.8 5335.7 5351.0 5055.7 5059.0 5145.0 5182.8 5227.5 5116.0 5194.8 5231.8 5251.5 5252.6 5106.1 5119.4 5139.4 5142.6 5187.1 3980.6 4194.6 5174.4 5503.7 5600.7 5224.4 6103.6 6125.2 6137.9 6204.4 6219.9 6234.8 6239.2 6241.5 6270.6 5349.0 5765.1 5803.6 5814.2 5878.9 6136.0 6159.0 6160.6 6171.0 6210.3 6058.0 6105.0 6123.5 6136.2 6148.6 6192.3 6202.3 6213.1 6213.7 6226.7 6012.7 6076.5 6080.2 6115.1 6132.5 6101.7 6151.2 6154.5 6157.5 6245.5 4170.2 4901.0 4921.1 4928.9 4954.8 4382.2 4396.1 4415.7 4426.0 4460.6 4683.7 5533.8 5750.2 5768.7 5810.5 6133.9 6180.5 6215.8 6231.4 6241.5 5281.6 5872.9 5876.7 5900.9 5903.6 5257.7 5261.4 5407.4 5420.5 5425.0 6193.2 6198.0 6230.0 6238.3 6251.3 6191.9 6219.2 6232.4 6235.2 6235.6 6270.3 6293.8 6300.1 6321.6 6341.1 6046.9 6101.6 6128.3 6182.8 6190.5 6146.4 6234.0 6246.6 6268.0 6283.3 5284.6 5981.1 5989.4 6150.1 6156.4 5787.7 5857.6 6020.8 6131.7 6158.2 4905.9 5187.8 5559.9 5701.3 5809.6 4166.4 4773.9 5018.7 5136.3 5938.3 5866.1 5891.5 5928.4 6037.3 6042.5 07:36:24 INFO - PROCESS | 1650 | 07:36:24 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:64630.80 Median:21585.66 stddev:138926.11 (643.6%) stddev-sans-first:139232.26 07:36:24 INFO - PROCESS | 1650 | Values: 449.7 472.4 541.5 556.6 565.5 36036.5 37050.9 39247.2 39327.8 39841.0 39615.6 40253.8 40291.2 40291.8 40313.6 9177.5 9381.4 9426.9 9433.8 9438.4 9285.8 9314.3 9389.0 9407.8 9436.3 9301.6 9392.8 9440.6 9441.7 9447.8 9274.5 9376.2 9420.3 9421.9 9444.2 7957.5 9180.3 9231.7 9294.0 9325.5 36793.8 37081.8 37422.8 37475.6 37487.8 3457.7 3825.6 3915.1 3924.1 3959.0 26797.7 29366.3 29794.8 29841.3 30549.7 3289.7 3306.7 3313.1 3349.6 3349.7 464748.7 465786.0 470855.6 470865.0 472578.0 57817.7 58275.4 58276.0 58383.4 58392.0 60504.0 60783.7 61071.0 61149.8 61300.4 15129.9 15285.0 15356.5 15366.8 15390.0 11834.3 11880.8 11885.0 11908.0 11925.8 12757.6 16205.3 16213.8 16257.2 16257.3 5417.8 6615.6 6662.0 6723.7 6742.2 801.4 943.2 953.7 983.2 1009.1 27915.2 28391.0 29513.4 29683.1 30022.7 955.6 1020.8 1069.5 1123.2 1137.6 15001.0 15025.8 15145.1 15187.8 15406.1 609129.0 640243.0 642941.0 643118.0 645571.0 53343.2 56083.0 56100.4 56769.0 56770.1 1145.6 1237.8 1315.2 1328.1 1358.5 24987.5 25667.5 26770.0 29988.9 30773.7 48241.8 48379.0 48398.8 48718.8 49039.8 430854.0 431255.0 431942.0 432518.0 434803.0 25269.0 25459.9 25538.0 25704.2 25722.2 44332.3 44688.8 44728.7 44863.3 44924.3 38028.4 38230.0 38855.8 38910.4 39178.8 36833.0 37123.8 37488.0 38322.0 38530.0 36770.6 37187.8 37702.6 37712.2 39027.4 31193.5 33663.2 34488.3 34526.1 34530.5 20021.6 25099.7 25194.4 25404.9 25811.5 07:36:24 INFO - PROCESS | 1650 | 07:36:24 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:32397.16 Median:359.50 stddev:91946.04 (25576.3%) stddev-sans-first:92172.45 07:36:24 INFO - PROCESS | 1650 | Values: 332.6 353.2 365.8 379.9 401.0 17799.5 18262.6 19260.2 19395.2 19566.5 18924.5 18945.5 19077.6 19163.0 19235.4 5579.4 5852.5 5875.0 5880.1 5883.6 5527.6 5750.7 5781.9 5793.9 5795.3 5607.0 5698.2 5723.3 5724.5 5746.7 5458.2 5742.9 5758.8 5826.2 5877.9 5464.5 5612.8 5655.5 5677.1 5687.4 12376.3 12597.7 12620.1 12755.8 14006.5 2058.4 2137.8 2156.7 2173.6 2484.4 8669.6 9371.8 9462.7 10550.5 14175.0 1598.8 1744.9 1796.9 1897.6 1950.1 110723.0 119136.2 119783.0 120292.0 122312.4 10737.4 11146.0 11250.6 11339.9 11730.6 14083.6 14093.1 14144.4 14155.6 14170.6 7932.3 8055.8 8119.6 8154.2 8180.9 7991.3 7994.8 8004.9 8079.9 8150.3 3766.9 4302.2 4343.2 4392.3 4402.3 1875.8 1922.5 1922.6 1929.5 1929.6 745.6 795.3 848.2 939.5 962.9 16798.1 18922.2 19089.0 19917.0 20280.5 864.2 916.5 942.4 954.4 963.2 7703.9 9706.8 10111.4 10575.9 10601.9 485397.0 516204.0 571791.0 577132.0 587296.6 106587.5 109203.5 112286.4 126955.0 137166.0 783.3 945.1 1001.0 1003.4 1007.7 17130.0 17338.9 17414.7 17525.0 17618.7 41538.0 52204.0 52899.0 53574.5 54388.5 65770.0 67854.5 70270.6 76163.6 76335.6 6719.8 7059.3 7122.9 7184.1 7325.4 9192.3 10663.4 12218.7 12502.2 12664.5 8687.4 9992.4 10632.8 10638.6 10767.8 10428.4 10516.0 10689.5 10727.5 10872.6 10614.3 11169.9 11215.0 11296.4 11300.1 7692.2 7843.9 8033.3 8106.8 8181.9 9017.5 10615.0 10861.2 10885.8 11047.8 07:36:24 INFO - PROCESS | 1650 | 07:36:24 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5309.17 Median:4946.90 stddev:2928.52 (59.2%) stddev-sans-first:2921.38 07:36:24 INFO - PROCESS | 1650 | Values: 1318.3 1448.4 1598.2 1776.2 1795.2 7146.8 7487.4 7758.5 8159.5 8515.7 7931.2 8225.1 8259.1 8363.6 8375.9 2286.3 2324.2 2329.3 2331.1 2342.1 2304.6 2341.1 2350.5 2351.2 2364.2 2261.8 2279.2 2292.0 2292.3 2292.5 2215.2 2237.2 2256.6 2267.4 2269.0 1809.4 1928.3 1956.9 2159.8 2324.0 8297.5 8323.0 8349.9 8360.8 8377.7 4271.6 4371.9 4592.2 5025.4 5175.9 3097.4 3241.4 3350.5 3454.6 3561.6 1038.1 1072.2 1082.7 1100.3 1105.3 9181.7 10261.8 10394.4 10503.4 10524.8 4454.6 4574.2 4695.6 4707.7 4753.3 9360.0 9403.2 9422.3 9426.1 9447.8 5176.3 5191.0 5197.6 5202.5 5206.4 4242.8 4265.5 4682.3 4740.2 4766.6 3180.3 3607.7 3690.0 3743.4 3765.9 1271.5 1279.7 1280.6 1290.2 1299.4 818.4 851.3 865.3 879.6 931.2 5127.8 5482.5 5531.1 5733.8 5797.4 415.8 423.7 431.9 436.3 439.5 3730.0 3745.6 3848.3 3861.1 3895.1 9747.6 9965.1 10105.9 10119.5 10133.3 8634.3 9875.8 9889.5 10064.2 10084.9 6100.2 7098.1 7592.5 7835.1 7848.5 4968.9 5304.9 5534.8 5539.4 5588.0 7811.4 9204.0 9271.9 9309.3 9346.8 9006.6 9371.0 9439.9 9580.4 9730.6 6528.6 6727.5 6905.3 7068.9 7146.2 6585.2 7746.3 7953.2 7956.2 7981.0 6335.3 6821.8 6998.4 7124.9 7319.7 6881.4 6929.6 6950.9 6961.1 7025.1 5677.5 6534.2 6560.4 6690.4 6722.8 4779.3 4825.6 4834.6 4866.1 4924.9 4659.2 4725.2 4879.7 4905.4 5005.5 07:36:24 INFO - PROCESS | 1650 | 07:36:24 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4500.92 Median:4895.10 stddev:1368.99 (28.0%) stddev-sans-first:1335.65 07:36:24 INFO - PROCESS | 1650 | Values: 278.7 297.6 303.3 309.1 313.4 4782.6 5272.9 5418.2 5502.8 5549.4 4747.5 5186.2 5287.9 5397.7 5413.8 3479.3 4108.1 4126.6 4158.6 4177.8 3276.7 3997.5 4082.9 4126.7 4215.5 3563.2 3814.0 4039.1 4112.7 4122.8 4110.6 4117.2 4126.7 4128.3 4161.2 3492.0 3577.5 3627.2 3644.7 3658.2 5049.5 5180.7 5278.2 5313.9 5351.3 4142.7 4395.3 4778.0 4796.1 4818.0 4944.5 5490.7 5736.7 5746.5 5749.7 3339.6 3990.9 4007.4 4043.5 4047.0 5249.7 5319.8 5996.2 6052.6 6075.7 3317.3 3904.6 4029.6 4052.7 4092.9 3747.0 4089.5 4137.2 4137.7 4150.0 4211.3 4721.3 4726.9 4749.1 4749.8 4888.2 5205.2 5269.4 5758.6 5794.9 1074.7 1281.4 1296.7 1329.0 1345.2 804.1 804.7 869.2 912.6 936.8 4496.0 4788.7 4832.8 4950.5 5068.5 4562.8 4865.5 5289.0 5471.3 5516.4 4637.5 4684.9 4932.9 5092.7 5121.2 2480.6 2790.6 2893.8 2910.3 2918.7 5108.7 5448.8 5642.9 5787.3 5793.6 5318.7 5351.6 5624.8 5687.7 5695.7 4589.7 5486.0 5710.7 5757.8 5790.7 4575.7 4742.5 5566.0 5574.5 5606.9 4654.7 4766.0 5332.8 5467.3 5467.7 5397.6 5476.2 5678.0 5735.6 5738.5 3316.4 3510.9 3823.8 3864.7 3880.4 4684.5 4902.0 5420.8 5615.8 5644.8 4577.8 5586.9 5744.2 5747.8 5748.7 5691.7 5713.2 5722.7 5727.3 5797.8 4712.5 4842.1 5426.7 5567.3 5589.7 4783.4 5653.8 5743.5 5748.7 5792.7 4654.8 5579.1 5653.9 5707.5 5720.1 07:36:24 INFO - PROCESS | 1650 | 07:36:24 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:21467.89 Median:30232.75 stddev:32690.32 (108.1%) stddev-sans-first:32744.79 07:36:24 INFO - PROCESS | 1650 | Values: 687.5 689.3 715.6 739.3 771.1 14322.2 14876.1 14913.5 14922.7 14928.3 13494.6 13633.8 13666.9 13676.5 13680.5 634.4 658.1 794.5 801.2 817.2 671.3 813.9 819.4 825.1 825.3 863.2 864.2 864.5 865.6 868.5 849.2 859.9 862.6 862.8 863.9 612.4 624.6 635.9 636.4 640.8 14293.4 14546.1 14682.4 14734.2 14878.2 13604.7 13697.2 13810.0 13921.4 13940.6 11452.1 11575.8 11823.8 11923.9 12135.0 3556.3 3588.9 3732.6 3749.3 3758.7 97605.5 99038.5 99102.5 99226.0 99430.5 10676.7 10724.6 10856.0 10870.5 10959.9 20611.7 21365.8 21401.3 21449.1 21452.9 6625.4 7677.2 7683.3 7711.4 7778.9 4224.7 4243.5 4277.8 4303.2 4397.4 3285.3 3347.2 3387.1 3399.7 3403.5 611.8 623.5 633.4 644.0 645.8 587.7 611.8 658.7 658.8 666.4 8424.5 8660.0 8784.5 8819.6 9130.8 632.6 651.3 651.3 677.5 694.1 4365.3 4495.6 4580.4 4582.8 4685.1 82682.6 116706.0 120930.0 120976.0 124575.0 69188.0 92573.5 106848.0 109218.5 109848.9 91396.0 94156.0 96163.5 96232.0 96745.0 7141.0 7588.5 7941.5 8689.4 8733.3 51882.5 53478.2 54189.9 59877.8 60288.7 81894.8 86259.6 87137.1 87773.0 87859.6 11586.6 11611.3 11897.9 12005.2 12078.0 12177.3 12201.3 12260.6 12658.0 12807.7 11423.9 11572.7 11720.8 11830.1 12015.1 11657.4 11762.4 11770.6 11950.8 12133.6 11389.1 11676.9 11797.8 11956.3 12104.5 9464.2 9492.3 9649.8 9651.4 9989.0 8586.5 9567.0 9822.7 9848.2 9877.7 07:36:24 INFO - PROCESS | 1650 | -------- Summary: end -------- 07:36:24 INFO - PROCESS | 1650 | 07:36:25 INFO - TEST-INFO | 1650: exit 0 07:36:25 INFO - TEST-OK | dromaeo_css | took 1117818ms 07:36:25 INFO - TEST-START | kraken 07:36:25 INFO - operating with platform_type : mac_ 07:36:25 INFO - Initialising browser for kraken test... 07:36:25 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmp0LGfq6/profile http://localhost:49242/getInfo.html) 07:36:28 INFO - PROCESS | 1659 | 2016-11-20 07:36:28.596 plugin-container[1660:10444] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 07:36:28 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:36:28 INFO - PROCESS | 1659 | 2016-11-20 07:36:28.599 plugin-container[1660:10444] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 07:36:28 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:36:28 INFO - PROCESS | 1659 | 2016-11-20 07:36:28.599 plugin-container[1660:10444] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:36:28 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 07:36:28 INFO - PROCESS | 1659 | colorDepth:24 07:36:28 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 07:36:28 INFO - PROCESS | 1659 | __metrics 07:36:28 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 07:36:29 INFO - TEST-INFO | 1659: exit 0 07:36:29 INFO - Browser initialized. 07:36:29 INFO - Running cycle 1/1 for kraken test... 07:36:29 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmp0LGfq6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:36:32 INFO - PROCESS | 1661 | 2016-11-20 07:36:32.454 plugin-container[1662:10563] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x463f, name = 'com.apple.tsm.portname' 07:36:32 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:36:32 INFO - PROCESS | 1661 | 2016-11-20 07:36:32.455 plugin-container[1662:10563] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.CFPasteboardClient' 07:36:32 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:36:32 INFO - PROCESS | 1661 | 2016-11-20 07:36:32.455 plugin-container[1662:10563] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:36:42 INFO - PROCESS | 1661 | RSS: Main: 242900992 07:36:42 INFO - PROCESS | 1661 | 07:36:45 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 07:36:46 INFO - PROCESS | 1661 | RSS: Main: 255303680 07:36:46 INFO - PROCESS | 1661 | 07:36:48 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 07:36:49 INFO - PROCESS | 1661 | RSS: Main: 262885376 07:36:49 INFO - PROCESS | 1661 | 07:36:52 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 07:36:53 INFO - PROCESS | 1661 | RSS: Main: 265924608 07:36:53 INFO - PROCESS | 1661 | 07:36:55 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 07:36:55 INFO - PROCESS | 1661 | RSS: Main: 268017664 07:36:55 INFO - PROCESS | 1661 | 07:36:58 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:36:58 INFO - PROCESS | 1661 | RSS: Main: 268320768 07:36:58 INFO - PROCESS | 1661 | 07:37:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 07:37:02 INFO - PROCESS | 1661 | RSS: Main: 273944576 07:37:02 INFO - PROCESS | 1661 | 07:37:05 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 07:37:05 INFO - PROCESS | 1661 | RSS: Main: 273903616 07:37:05 INFO - PROCESS | 1661 | 07:37:08 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 07:37:09 INFO - PROCESS | 1661 | RSS: Main: 273850368 07:37:09 INFO - PROCESS | 1661 | 07:37:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:37:11 INFO - PROCESS | 1661 | RSS: Main: 272834560 07:37:11 INFO - PROCESS | 1661 | 07:37:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:37:14 INFO - PROCESS | 1661 | RSS: Main: 272883712 07:37:14 INFO - PROCESS | 1661 | 07:37:16 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:37:16 INFO - PROCESS | 1661 | RSS: Main: 272912384 07:37:16 INFO - PROCESS | 1661 | 07:37:19 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:37:20 INFO - PROCESS | 1661 | RSS: Main: 273260544 07:37:20 INFO - PROCESS | 1661 | 07:37:23 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:37:24 INFO - PROCESS | 1661 | RSS: Main: 273289216 07:37:24 INFO - PROCESS | 1661 | 07:37:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 07:37:26 INFO - PROCESS | 1661 | RSS: Main: 274849792 07:37:26 INFO - PROCESS | 1661 | 07:37:26 INFO - PROCESS | 1661 | __start_tp_report 07:37:26 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 07:37:26 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 07:37:26 INFO - PROCESS | 1661 | |i|pagename|runs| 07:37:26 INFO - PROCESS | 1661 | |0;ai-astar;106;121;127;117;120;121;114;118;120;118 07:37:26 INFO - PROCESS | 1661 | |1;audio-beat-detection;145;113;117;146;113;114;115;132;169;118 07:37:26 INFO - PROCESS | 1661 | |2;audio-dft;207;231;196;199;194;195;195;231;277;190 07:37:26 INFO - PROCESS | 1661 | |3;audio-fft;84;59;60;110;83;85;70;61;77;58 07:37:26 INFO - PROCESS | 1661 | |4;audio-oscillator;89;69;90;60;70;82;122;102;103;97 07:37:26 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;90;88;89;100;91;86;92;88;92;87 07:37:26 INFO - PROCESS | 1661 | |6;imaging-darkroom;104;97;96;100;99;99;154;97;102;99 07:37:26 INFO - PROCESS | 1661 | |7;imaging-desaturate;93;93;93;96;97;90;91;97;94;91 07:37:26 INFO - PROCESS | 1661 | |8;json-parse-financial;57;56;57;58;61;80;89;90;92;48 07:37:26 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;78;94;68;86;79;76;70;71;71;72 07:37:26 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;90;91;91;87;89;89;88;92;87;91 07:37:26 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;153;149;150;153;151;170;153;154;227;189 07:37:26 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;171;177;222;244;199;177;178;180;175;175 07:37:26 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;68;70;70;69;67;71;83;76;68;61 07:37:26 INFO - PROCESS | 1661 | __end_tp_report 07:37:26 INFO - PROCESS | 1661 | __start_cc_report 07:37:26 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,933 07:37:26 INFO - PROCESS | 1661 | __end_cc_report 07:37:26 INFO - PROCESS | 1661 | __startTimestamp1479656246342__endTimestamp 07:37:26 INFO - PROCESS | 1661 | 07:37:26 INFO - PROCESS | 1661 | ------- Summary: start ------- 07:37:26 INFO - PROCESS | 1661 | Number of tests: 14 07:37:26 INFO - PROCESS | 1661 | 07:37:26 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:118.20 Median:120.00 stddev:5.45 (4.5%) stddev-sans-first:3.57 07:37:26 INFO - PROCESS | 1661 | Values: 106.0 121.0 127.0 117.0 120.0 121.0 114.0 118.0 120.0 118.0 07:37:26 INFO - PROCESS | 1661 | 07:37:26 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:128.20 Median:125.00 stddev:19.28 (15.4%) stddev-sans-first:19.47 07:37:26 INFO - PROCESS | 1661 | Values: 145.0 113.0 117.0 146.0 113.0 114.0 115.0 132.0 169.0 118.0 07:37:26 INFO - PROCESS | 1661 | 07:37:26 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:211.50 Median:203.00 stddev:27.45 (13.5%) stddev-sans-first:29.06 07:37:26 INFO - PROCESS | 1661 | Values: 207.0 231.0 196.0 199.0 194.0 195.0 195.0 231.0 277.0 190.0 07:37:26 INFO - PROCESS | 1661 | 07:37:26 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:74.70 Median:73.50 stddev:16.55 (22.5%) stddev-sans-first:17.20 07:37:26 INFO - PROCESS | 1661 | Values: 84.0 59.0 60.0 110.0 83.0 85.0 70.0 61.0 77.0 58.0 07:37:26 INFO - PROCESS | 1661 | 07:37:26 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:76.00 stddev:18.76 (24.7%) stddev-sans-first:19.89 07:37:26 INFO - PROCESS | 1661 | Values: 89.0 69.0 90.0 60.0 70.0 82.0 122.0 102.0 103.0 97.0 07:37:26 INFO - PROCESS | 1661 | 07:37:26 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:89.50 stddev:3.97 (4.4%) stddev-sans-first:4.21 07:37:26 INFO - PROCESS | 1661 | Values: 90.0 88.0 89.0 100.0 91.0 86.0 92.0 88.0 92.0 87.0 07:37:26 INFO - PROCESS | 1661 | 07:37:26 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:104.70 Median:97.00 stddev:17.49 (18.0%) stddev-sans-first:18.55 07:37:26 INFO - PROCESS | 1661 | Values: 104.0 97.0 96.0 100.0 99.0 99.0 154.0 97.0 102.0 99.0 07:37:26 INFO - PROCESS | 1661 | 07:37:26 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:93.50 Median:93.50 stddev:2.51 (2.7%) stddev-sans-first:2.65 07:37:26 INFO - PROCESS | 1661 | Values: 93.0 93.0 93.0 96.0 97.0 90.0 91.0 97.0 94.0 91.0 07:37:26 INFO - PROCESS | 1661 | 07:37:26 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:68.80 Median:70.50 stddev:16.91 (24.0%) stddev-sans-first:17.39 07:37:26 INFO - PROCESS | 1661 | Values: 57.0 56.0 57.0 58.0 61.0 80.0 89.0 90.0 92.0 48.0 07:37:26 INFO - PROCESS | 1661 | 07:37:26 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:76.50 Median:77.00 stddev:8.17 (10.6%) stddev-sans-first:8.65 07:37:26 INFO - PROCESS | 1661 | Values: 78.0 94.0 68.0 86.0 79.0 76.0 70.0 71.0 71.0 72.0 07:37:26 INFO - PROCESS | 1661 | 07:37:26 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:90.50 stddev:1.78 (2.0%) stddev-sans-first:1.88 07:37:26 INFO - PROCESS | 1661 | Values: 90.0 91.0 91.0 87.0 89.0 89.0 88.0 92.0 87.0 91.0 07:37:26 INFO - PROCESS | 1661 | 07:37:26 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:164.90 Median:153.50 stddev:25.11 (16.4%) stddev-sans-first:26.26 07:37:26 INFO - PROCESS | 1661 | Values: 153.0 149.0 150.0 153.0 151.0 170.0 153.0 154.0 227.0 189.0 07:37:26 INFO - PROCESS | 1661 | 07:37:26 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.80 Median:179.00 stddev:24.53 (13.7%) stddev-sans-first:25.05 07:37:26 INFO - PROCESS | 1661 | Values: 171.0 177.0 222.0 244.0 199.0 177.0 178.0 180.0 175.0 175.0 07:37:26 INFO - PROCESS | 1661 | 07:37:26 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:70.00 stddev:5.81 (8.3%) stddev-sans-first:6.11 07:37:26 INFO - PROCESS | 1661 | Values: 68.0 70.0 70.0 69.0 67.0 71.0 83.0 76.0 68.0 61.0 07:37:26 INFO - PROCESS | 1661 | -------- Summary: end -------- 07:37:26 INFO - PROCESS | 1661 | 07:37:27 INFO - TEST-INFO | 1661: exit 0 07:37:27 INFO - TEST-OK | kraken | took 62013ms 07:37:27 INFO - SUITE-END | took 1179s 07:37:27 INFO - Completed test suite (00:19:39) 07:37:27 INFO - javascript benchmark 07:37:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7011.580740253841, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3078.5843911072006, 3347.715444187244, 3366.20894089889, 3388.593368795745, 3393.7963722176673, 4390.0, 5607.943920560793, 5692.544596432286, 5773.566982476315, 5824.271966004249, 4995.5762607656825, 5021.246812978065, 5627.971860140694, 5692.345380281269, 5795.21764561784, 5085.262636917645, 5158.638895277332, 5259.132243179871, 5284.392294886099, 5292.603054770889, 5095.490450954893, 5279.419263880989, 5315.7873685052555, 5335.7332133393365, 5350.973245133789, 5055.6713813602, 5059.038782631288, 5144.9742751286, 5182.844514664566, 5227.529522343008, 5116.0, 5194.766235519411, 5231.764570594332, 5251.474852514756, 5252.606054545924, 5106.08090543702, 5119.411267704225, 5139.383274007143, 5142.61430392722, 5187.066328060948, 3980.641742243212, 4194.622483976426, 5174.379074511083, 5503.724813759336, 5600.719964001783, 5224.39919409269, 6103.61042559425, 6125.2049292651645, 6137.928623859655, 6204.379562043805, 6219.906701399483, 6234.812955611339, 6239.188905442309, 6241.531885108589, 6270.592411493239, 5348.9732551337775, 5765.07758758605, 5803.564732645069, 5814.156947242626, 5878.853028674317, 6136.028952951454, 6158.969205153946, 6160.630362178194, 6171.0, 6210.285817130997, 6058.0, 6105.035213715927, 6123.540734444934, 6136.151476879544, 6148.600340977822, 6192.287886892975, 6202.28673702521, 6213.130161777355, 6213.689315534205, 6226.688665566794, 6012.699365031731, 6076.5442591805795, 6080.209572755513, 6115.113308570232, 6132.478739307209, 6101.671357587133, 6151.200343955346, 6154.5384096192065, 6157.476614487729, 6245.469135123474, 4170.22850772608, 4901.019796040806, 4921.114199444134, 4928.926066109011, 4954.801807927643, 4382.247101159562, 4396.142752163361, 4415.66882483815, 4426.026274219666, 4460.576245256695, 4683.718976861399, 5533.750981205855, 5750.166225897222, 5768.711564421762, 5810.477057064884, 6133.885575987479, 6180.505559555225, 6215.782447614402, 6231.376862313823, 6241.500679945593, 5281.630285879952, 5872.853178670567, 5876.7355469004, 5900.881982360329, 5903.557233207441, 5257.737113144328, 5261.447548007481, 5407.351117865881, 5420.457954204548, 5425.0, 6193.194884664965, 6197.977333739883, 6230.0, 6238.282597501255, 6251.281102673069, 6191.916414627332, 6219.160413344322, 6232.407921247474, 6235.189425374672, 6235.594686345373, 6270.278917924315, 6293.842653933688, 6300.149479820166, 6321.589096708699, 6341.112244285711, 6046.939530604637, 6101.60339577926, 6128.264608247039, 6182.752689892354, 6190.473809726223, 6146.416090471398, 6234.0, 6246.562740608114, 6268.0, 6283.277423096402, 5284.5995811109315, 5981.102834574848, 5989.431004054608, 6150.138980542817, 6156.445919867234, 5787.7395517200985, 5857.648541087548, 6020.819375418744, 6131.662758548145, 6158.230221222348, 4905.852824415273, 5187.832737634002, 5559.861003474945, 5701.287339082614, 5809.593328466966, 4166.354215096665, 4773.902002539366, 5018.673786203885, 5136.332276803992, 5938.287405511366, 5866.090755932837, 5891.49922256613, 5928.407159284209, 6037.275526936795, 6042.516598672008], "value": 5567.327239236438, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.707690001499, 472.3575936726053, 541.5261646059698, 556.6131538580688, 565.5334349161938, 36036.459453108226, 37050.88847334581, 39247.21505569891, 39327.803360983155, 39841.0039749006, 39615.60384396155, 40253.7987310063, 40291.194176116536, 40291.79854100733, 40313.59686403135, 9177.495237761921, 9381.437113773196, 9426.905730942706, 9433.76415589609, 9438.386504877195, 9285.767855803626, 9314.347995640308, 9388.953055234715, 9407.81184376312, 9436.292278079138, 9301.581428835685, 9392.765180870465, 9440.575174117146, 9441.669541566047, 9447.81104377912, 9274.48990305533, 9376.249900008017, 9420.340576159671, 9421.905780942172, 9444.244460443217, 7957.522548647042, 9180.265578753684, 9231.723048308562, 9293.953530232373, 9325.533723313807, 36793.816030919676, 37081.81459092714, 37422.8128859354, 37475.62524374748, 37487.81256093702, 3457.6888080072663, 3825.5791862895057, 3915.0799562102884, 3924.0974575847713, 3959.0, 26797.73202267972, 29366.265843353875, 29794.808207671667, 29841.25396865074, 30549.694503054907, 3289.6874796894494, 3306.718928891047, 3313.1054615253747, 3349.6482869298616, 3349.6650334966553, 464748.67625661654, 465786.0, 470855.6457217726, 470865.0, 472578.0, 57817.710911445174, 58275.41724582785, 58276.0, 58383.41616583865, 58392.0, 60504.0, 60783.69608151931, 61071.0, 61149.77700445966, 61300.38699613036, 15129.940904136605, 15285.006474579339, 15356.462523811613, 15366.84633153654, 15390.0, 11834.289942603471, 11880.762384752257, 11885.049196064467, 11908.0, 11925.761484770255, 12757.617271481871, 16205.270762815702, 16213.756793648105, 16257.18714064292, 16257.26842292076, 5417.83746487606, 6615.6030638161865, 6662.0, 6723.697433615499, 6742.224644165885, 801.3669201330883, 943.2218419803552, 953.7043516509904, 983.1692220074026, 1009.0817356205818, 27915.16254512368, 28391.0, 29513.409731805245, 29683.109506714834, 30022.699773002427, 955.6320816485703, 1020.811149937264, 1069.4759567811873, 1123.2361993844265, 1137.6359564939305, 15001.0, 15025.849741502663, 15145.091294522364, 15187.772183417257, 15406.075635461908, 609129.0, 640243.0, 642941.0, 643118.0, 645571.0, 53343.19985200225, 56083.0, 56100.43899560952, 56769.0, 56770.14844777332, 1145.616218566773, 1237.8452693413144, 1315.1780137414116, 1328.103530117167, 1358.456617353047, 24987.50024999526, 25667.486650266517, 26770.0, 29988.850055749583, 30773.692263077082, 48241.75879120582, 48379.0, 48398.790030249525, 48718.75640621916, 49039.75480122577, 430854.0, 431255.0, 431942.0, 432518.0, 434803.0, 25268.989240430175, 25459.872700636377, 25538.0, 25704.228873133838, 25722.228333150037, 44332.33501497415, 44688.77655611701, 44728.658140255844, 44863.32705009362, 44924.326135108655, 38028.42957355586, 38230.044248894, 38855.834324970296, 38910.41634375543, 39178.80410597929, 36833.0, 37123.81438092792, 37488.0, 38322.0, 38530.0, 36770.63229367672, 37187.81406092952, 37702.62297377101, 37712.24575508529, 39027.41458878176, 31193.532097019015, 33663.15842103967, 34488.31023379569, 34526.13684657904, 34530.482042768876, 20021.599568008267, 25099.74900251047, 25194.37014074663, 25404.872975635004, 25811.483770324863], "value": 19127.887679986306, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.5776264144535, 353.23877044968094, 365.7549441873944, 379.9449079883416, 400.96190861868115, 17799.466016019524, 18262.634747305063, 19260.229590816387, 19395.22419103236, 19566.510837229078, 18924.526886827836, 18945.526361840963, 19077.618447631037, 19163.041847907618, 19235.42293731187, 5579.414161513034, 5852.531797456193, 5875.0, 5880.147378630096, 5883.558733095035, 5527.585431092684, 5750.712464376786, 5781.855453613652, 5793.9281232971825, 5795.304563452391, 5607.0, 5698.173764804101, 5723.284589426322, 5724.513416359614, 5746.655200687972, 5458.235846981426, 5742.885142297131, 5758.82723518293, 5826.184334193216, 5877.882442351171, 5464.535514481274, 5612.775488980476, 5655.462731040586, 5677.091665333568, 5687.431256874321, 12376.319302438362, 12597.748045039049, 12620.116591838574, 12755.808662870064, 14006.509772158028, 2058.4133521946173, 2137.7542130286574, 2156.7196264485515, 2173.608750424923, 2484.416162201881, 8669.61286194206, 9371.76570585734, 9462.716118516455, 10550.525226364833, 14175.007749457542, 1598.8081430228447, 1744.877858549902, 1796.8921864688161, 1897.6489349470392, 1950.1224448998007, 110723.0, 119136.21295680485, 119783.0, 120292.0, 122312.38843805813, 10737.355758654505, 11146.0, 11250.606228782117, 11339.886601134049, 11730.648080557597, 14083.647908802363, 14093.083949543452, 14144.434222631186, 14155.575332740034, 14170.64573385653, 7932.286094251545, 8055.838883222303, 8119.5534245617055, 8154.2253485918745, 8180.918190818015, 7991.2807847294025, 7994.760157195294, 8004.919950800418, 8079.878801817978, 8150.3479721622125, 3766.851110411296, 4302.247106756305, 4343.1747967886, 4392.275274579659, 4402.295632698753, 1875.7842848072373, 1922.4617107209724, 1922.6058657975082, 1929.5465565592074, 1929.556202073531, 745.6309126982178, 795.3200013988072, 848.2365870716404, 939.5302348825587, 962.9277804164716, 16798.076105814296, 18922.24311027571, 19089.04554772256, 19917.0, 20280.505745298527, 864.1962974433837, 916.5417291354323, 942.3874481587023, 954.4464210757883, 963.21016766251, 7703.8844417333785, 9706.835179778473, 10111.393316400892, 10575.894241057646, 10601.946990265, 485397.0, 516204.0, 571791.0, 577132.0, 587296.5716542766, 106587.46706266575, 109203.45398272958, 112286.43856780663, 126955.0, 137166.0, 783.3106865958034, 945.1352012908112, 1000.9999999999854, 1003.4280460137652, 1007.7480629842539, 17129.97220166794, 17338.93501516867, 17414.73877891858, 17525.0, 17618.73571896397, 41538.0, 52204.0, 52899.0, 53574.46425535695, 54388.45611543992, 65770.0, 67854.50036994726, 70270.64864675644, 76163.61918190373, 76335.61832190804, 6719.798406047827, 7059.2940705928995, 7122.893156602759, 7184.137903451619, 7325.413966882743, 9192.310576706908, 10663.360198388122, 12218.652725676346, 12502.249865008129, 12664.493420263087, 8687.435316704394, 9992.400455972665, 10632.787344253225, 10638.574457021632, 10767.800557782315, 10428.374297542172, 10516.0, 10689.465526723478, 10727.463626818471, 10872.61945831892, 10614.257002009785, 11169.888301116885, 11215.046721028804, 11296.378699171624, 11300.09599232043, 7692.192319806563, 7843.921560784319, 8033.277005069572, 8106.837863242584, 8181.918180818116, 9017.458952462612, 10615.0, 10861.239713219999, 10885.782284354427, 11047.834282485608], "value": 8970.571797202161, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1318.2947123289039, 1448.3627204030226, 1598.2408356030887, 1776.2362184260762, 1795.237024264688, 7146.821329466759, 7487.401007919367, 7758.456908016441, 8159.469634473755, 8515.701950431736, 7931.246531579505, 8225.054118776343, 8259.132791056943, 8363.581820908961, 8375.874361884577, 2286.2798218561184, 2324.2213858357386, 2329.2546385156757, 2331.1258278145697, 2342.1334106380664, 2304.608216603173, 2341.1103780563362, 2350.471143992605, 2351.235848349285, 2364.243442098529, 2261.7738226177416, 2279.225063478417, 2292.0373443153917, 2292.255017119443, 2292.4727312718087, 2215.2246713650093, 2237.19460994042, 2256.593813113639, 2267.4104732769515, 2268.9546209075725, 1809.4209852847093, 1928.2865339824355, 1956.9217231310731, 2159.75162856272, 2323.9883800580888, 8297.460665056751, 8323.001239851195, 8349.874751878726, 8360.83278334436, 8377.706780262723, 4271.63691086258, 4371.868843934687, 4592.242280023792, 5025.422076461181, 5175.922361164586, 3097.3805238952073, 3241.3679332530164, 3350.5141754445713, 3454.5854497460464, 3561.6260292669413, 1038.086483894176, 1072.1529991306859, 1082.658962426833, 1100.3452945497415, 1105.2981356838466, 9181.724548263563, 10261.79476410475, 10394.376337419704, 10503.422311772849, 10524.789504209873, 4454.5990860822685, 4574.153781550423, 4695.577398034125, 4707.7175369477945, 4753.31076993834, 9360.0, 9403.200727938201, 9422.340436169403, 9426.104520070583, 9447.779771753632, 5176.301199338116, 5191.0, 5197.636165468381, 5202.505761952609, 5206.375234971828, 4242.787860606957, 4265.466816647919, 4682.297655351725, 4740.241561350167, 4766.642501812378, 3180.268538236219, 3607.6753092221825, 3690.0, 3743.3636281832155, 3765.9246815063543, 1271.4786937355825, 1279.6736832107938, 1280.5582074184392, 1290.180735233115, 1299.3698056442618, 818.3575892924026, 851.314691673209, 865.2731705367396, 879.6437442835662, 931.1619542411748, 5127.79488820443, 5482.479164479368, 5531.087370583889, 5733.799317023884, 5797.449242321973, 415.8399016378655, 423.6737711961772, 431.939528466015, 436.32587652076984, 439.5340938605089, 3729.9813500932323, 3745.6067112952746, 3848.2688289224957, 3861.131245469803, 3895.1235971906094, 9747.56135973883, 9965.10314071737, 10105.898941010642, 10119.494025298705, 10133.2906696532, 8634.266087382517, 9875.755975569677, 9889.50552472388, 10064.194864410829, 10084.899151008396, 6100.23747031621, 7098.148222213367, 7592.544447333067, 7835.0989636191425, 7848.529088254723, 4968.925466118083, 5304.9469505306, 5534.833954981357, 5539.362973257966, 5588.0, 7811.359614481023, 9204.033576474641, 9271.907280927104, 9309.348345615741, 9346.766330841783, 9006.59470323824, 9371.0, 9439.858402124111, 9580.425174489274, 9730.610775568897, 6528.608283503005, 6727.461803055841, 6905.309469053054, 7068.893966590608, 7146.178189508273, 6585.176852893388, 7746.30283274508, 7953.204679532, 7956.2043795619975, 7980.960095199487, 6335.334789847, 6821.761238356634, 6998.440124790106, 7124.857502850017, 7319.670614822239, 6881.41507971828, 6929.5842249465195, 6950.8609827804175, 6961.129858767654, 7025.086738723933, 5677.545796336366, 6534.248561415308, 6560.442362399059, 6690.364415380531, 6722.798316050526, 4779.330893674819, 4825.5898248649255, 4834.564889159992, 4866.099771542276, 4924.8768780780765, 4659.20793465105, 4725.173094708343, 4879.682820616648, 4905.3623029006, 5005.524475174852], "value": 4240.677665734352, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.6725597423028, 297.57892581996464, 303.322075162013, 309.1205520294762, 313.3920194822045, 4782.641301902354, 5272.864489881595, 5418.160185171295, 5502.834914952553, 5549.38956714761, 4747.548982846624, 5186.247994040871, 5287.889543195924, 5397.705974960646, 5413.83758487246, 3479.3389256041273, 4108.116754897696, 4126.6079722426475, 4158.563350848162, 4177.832886684529, 3276.65657080597, 3997.4603428537066, 4082.8979275518054, 4126.739875498352, 4215.536291007987, 3563.2339047104865, 3813.9618603813883, 4039.0508230565792, 4112.691548133902, 4122.752634841919, 4110.6300432961025, 4117.197146556423, 4126.711130220886, 4128.318827393477, 4161.188568229197, 3491.9650803491895, 3577.481265216555, 3627.220147668264, 3644.7084233261276, 3658.213484100931, 5049.469805670426, 5180.6891586504935, 5278.2346559749, 5313.920291195635, 5351.260840226942, 4142.71001029928, 4395.296752519614, 4777.952220477786, 4796.088743138793, 4818.0, 4944.505549445063, 5490.69801160936, 5736.741846616912, 5746.511546518551, 5749.728857101463, 3339.632640409553, 3990.86031988879, 4007.438958545806, 4043.514778226602, 4047.0489434982755, 5249.737513124367, 5319.760610772486, 5996.19051428056, 6052.636841789506, 6075.6962151891785, 3317.286783341545, 3904.6095390460728, 4029.5768944261017, 4052.7163098583405, 4092.856750013735, 3747.0070431335716, 4089.5073298245925, 4137.234611596864, 4137.668986481074, 4150.0, 4211.284081706117, 4721.291806229093, 4726.905461890743, 4749.105733298662, 4749.833755818529, 4888.242322440027, 5205.219217117463, 5269.446708095671, 5758.568107391962, 5794.942050579524, 1074.7313171707074, 1281.398252184769, 1296.7486375647545, 1328.973420531584, 1345.1705680274817, 804.0994086623019, 804.6821505505293, 869.2220462685987, 912.589334799343, 936.8032713130319, 4495.977520112444, 4788.688735232199, 4832.806687732522, 4950.504949505021, 5068.467810879804, 4562.794674239601, 4865.489123641966, 5288.9735551322, 5471.2887324647545, 5516.393196748354, 4637.46669133054, 4684.859454216379, 4932.93941802507, 5092.719900405513, 5121.156383701865, 2480.6031035034307, 2790.623265859083, 2893.811902226391, 2910.264351509574, 2918.678945316056, 5108.744562771846, 5448.754806033738, 5642.943570564242, 5787.334456537552, 5793.594448388657, 5318.680879147263, 5351.601732899637, 5624.803131890446, 5687.720262940805, 5695.658260504383, 4589.747563884019, 5485.9725701371235, 5710.71446427677, 5757.798477053283, 5790.681512516852, 4575.702579332466, 4742.454617718869, 5565.972170139124, 5574.470425309589, 5606.859828504157, 4654.6741728078705, 4765.975315307225, 5332.840014799563, 5467.343918729778, 5467.699276539828, 5397.649152805055, 5476.205950137288, 5678.041075828833, 5735.569832262514, 5738.483536481736, 3316.3864685033345, 3510.9291666042104, 3823.847046118124, 3864.7294689372598, 3880.3597406428753, 4684.508126646653, 4901.965684241, 5420.83737487876, 5615.820677657574, 5644.810681964676, 4577.8397756078375, 5586.944130558642, 5744.195812586157, 5747.827565173052, 5748.6838223898085, 5691.686957217393, 5713.200151978644, 5722.656640601577, 5727.341355743976, 5797.797077102281, 4712.468447754425, 4842.080004799077, 5426.70153141581, 5567.3040869891265, 5589.692566908697, 4783.354247176726, 5653.77734691222, 5743.540516758566, 5748.712564371848, 5792.681402522901, 4654.767261636973, 5579.094633756468, 5653.886922261614, 5707.514861236841, 5720.11338242573], "value": 4059.867865141372, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.5153017122929, 689.2659317826515, 715.6493318274046, 739.2865884421532, 771.0901136658744, 14322.212278324701, 14876.107433553967, 14913.478028269014, 14922.701545969074, 14928.25358732063, 13494.595162145151, 13633.795493067611, 13666.931665341659, 13676.52132175374, 13680.452781888738, 634.3719717479685, 658.0556900847275, 794.4637369775407, 801.1707882341777, 817.207308910359, 671.318611609217, 813.894193754811, 819.4345901328095, 825.0717942314895, 825.331481499984, 863.2144748279068, 864.2394692670473, 864.4553931023478, 865.6234537975969, 868.4875923205308, 849.1847826086964, 859.8710193471029, 862.5859587398053, 862.779991102268, 863.9395242333039, 612.3753771153443, 624.5596854217765, 635.8823617630753, 636.4176778247897, 640.810960766575, 14293.356798943969, 14546.054506457152, 14682.412703491955, 14734.18961957092, 14878.181700006495, 13604.659883502893, 13697.178169309873, 13810.0, 13921.373538190806, 13940.58178254654, 11452.083833293316, 11575.76848463026, 11823.763524729457, 11923.94038029813, 12134.968527675159, 3556.324298383299, 3588.910277243064, 3732.570754363229, 3749.2876353492748, 3758.68051215647, 97605.51197244039, 99038.50480747622, 99102.50448747783, 99226.0, 99430.50284748603, 10676.679699609023, 10724.571017159225, 10856.0, 10870.510827012804, 10959.917615653001, 20611.660242084217, 21365.78634213669, 21401.250956216456, 21449.142034318764, 21452.927353632567, 6625.370589793962, 7677.155512893577, 7683.270089341504, 7711.383089352838, 7778.922210777933, 4224.683148763855, 4243.533211346749, 4277.828886844553, 4303.160883627663, 4397.428334316583, 3285.3100848822014, 3347.1799409144915, 3387.1024178592693, 3399.677030682131, 3403.540522029544, 611.8133969139366, 623.5230049012509, 633.3634697129355, 643.9871202575922, 645.8030300758314, 587.6885250817073, 611.7552978808512, 658.720043981306, 658.805652332563, 666.4401902402005, 8424.4945303282, 8659.956700216584, 8784.472931624123, 8819.603117859711, 9130.771730706785, 632.5824955529329, 651.3291309950758, 651.3356376496042, 677.4580335731396, 694.1150033706963, 4365.345198220229, 4495.64034877209, 4580.358749775034, 4582.839600613963, 4685.0629874025335, 82682.58658706667, 116706.0, 120930.0, 120976.0, 124575.0, 69188.0, 92573.53713231391, 106848.0, 109218.53907304603, 109848.90151098547, 91396.0, 94156.0, 96163.51918240504, 96232.0, 96745.0, 7141.000259963713, 7588.506747061423, 7941.483803552751, 8689.391742578211, 8733.30133589324, 51882.481175187764, 53478.17610094447, 54189.916201674954, 59877.80244395175, 60288.69855650693, 81894.77157842756, 86259.56870215609, 87137.1286287129, 87773.0, 87859.56070219607, 11586.594469193538, 11611.303321800719, 11897.94051029774, 12005.219660722025, 12078.0, 12177.269363838199, 12201.328926909106, 12260.570880019513, 12658.0, 12807.743845122859, 11423.885761142283, 11572.710682233012, 11720.765584688428, 11830.112741544246, 12015.09886758514, 11657.41712914334, 11762.3530705812, 11770.58802941893, 11950.820737689115, 12133.575324863587, 11389.145814064144, 11676.88323116792, 11797.76404471923, 11956.34240116802, 12104.51581936748, 9464.19554337889, 9492.335536512377, 9649.758756031155, 9651.372660777028, 9988.951160128372, 8586.527740974056, 9567.0, 9822.65620703272, 9848.212143028397, 9877.703668889944], "value": 7223.8314978929175, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1569.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 121.0, 127.0, 117.0, 120.0, 121.0, 114.0, 118.0, 120.0, 118.0], "value": 118.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 113.0, 117.0, 146.0, 113.0, 114.0, 115.0, 132.0, 169.0, 118.0], "value": 128.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 231.0, 196.0, 199.0, 194.0, 195.0, 195.0, 231.0, 277.0, 190.0], "value": 211.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 60.0, 110.0, 83.0, 85.0, 70.0, 61.0, 77.0, 58.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 69.0, 90.0, 60.0, 70.0, 82.0, 122.0, 102.0, 103.0, 97.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 89.0, 100.0, 91.0, 86.0, 92.0, 88.0, 92.0, 87.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 97.0, 96.0, 100.0, 99.0, 99.0, 154.0, 97.0, 102.0, 99.0], "value": 104.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 96.0, 97.0, 90.0, 91.0, 97.0, 94.0, 91.0], "value": 93.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 58.0, 61.0, 80.0, 89.0, 90.0, 92.0, 48.0], "value": 68.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 94.0, 68.0, 86.0, 79.0, 76.0, 70.0, 71.0, 71.0, 72.0], "value": 76.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 91.0, 87.0, 89.0, 89.0, 88.0, 92.0, 87.0, 91.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 149.0, 150.0, 153.0, 151.0, 170.0, 153.0, 154.0, 227.0, 189.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 177.0, 222.0, 244.0, 199.0, 177.0, 178.0, 180.0, 175.0, 175.0], "value": 189.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 70.0, 69.0, 67.0, 71.0, 83.0, 76.0, 68.0, 61.0], "value": 70.3, "unit": "score"}]}]} 07:37:27 INFO - Return code: 0 07:37:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:37:27 ERROR - # TBPL SUCCESS # 07:37:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:37:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:37:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:37:27 INFO - 2016-11-20 07:37:27.696 screenresolution[1666:10959] starting screenresolution argv=screenresolution get 07:37:27 INFO - 2016-11-20 07:37:27.699 screenresolution[1666:10959] Display 0: 1600x1200x32@60 07:37:27 INFO - 2016-11-20 07:37:27.705 screenresolution[1667:10962] starting screenresolution argv=screenresolution list 07:37:27 INFO - Available Modes on Display 0 07:37:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:37:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:37:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:37:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:37:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:37:27 INFO - Intel Iris: 07:37:27 INFO - Chipset Model: Intel Iris 07:37:27 INFO - Type: GPU 07:37:27 INFO - Bus: Built-In 07:37:27 INFO - VRAM (Dynamic, Max): 1536 MB 07:37:27 INFO - Vendor: Intel (0x8086) 07:37:27 INFO - Device ID: 0x0a2e 07:37:27 INFO - Revision ID: 0x0009 07:37:27 INFO - Displays: 07:37:27 INFO - Display: 07:37:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:37:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:37:27 INFO - Display Serial Number: 1600x1200 60 07:37:27 INFO - Main Display: Yes 07:37:27 INFO - Mirror: Off 07:37:27 INFO - Online: Yes 07:37:27 INFO - Rotation: Supported 07:37:27 INFO - Return code: 0 07:37:27 INFO - Running post-action listener: _resource_record_post_action 07:37:27 INFO - [mozharness: 2016-11-20 15:37:27.955126Z] Finished run-tests step (success) 07:37:27 INFO - Running post-run listener: _resource_record_post_run 07:37:28 INFO - Total resource usage - Wall time: 1214s; CPU: 55.0%; Read bytes: 33206784; Write bytes: 697728000; Read time: 208; Write time: 2002 07:37:28 INFO - TinderboxPrint: CPU usage
54.6% 07:37:28 INFO - TinderboxPrint: I/O read bytes / time
33,206,784 / 208 07:37:28 INFO - TinderboxPrint: I/O write bytes / time
697,728,000 / 2,002 07:37:28 INFO - TinderboxPrint: CPU idle
2,203.0 (45.4%) 07:37:28 INFO - TinderboxPrint: CPU system
983.9 (20.3%) 07:37:28 INFO - TinderboxPrint: CPU user
1,670.7 (34.4%) 07:37:28 INFO - TinderboxPrint: Swap in / out
412,790,784 / 0 07:37:28 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 374436352; Write bytes: 369002496; Read time: 29093; Write time: 767 07:37:28 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 31244800; Write bytes: 310207488; Read time: 191; Write time: 1185 07:37:28 INFO - Running post-run listener: _upload_blobber_files 07:37:28 INFO - Blob upload gear active. 07:37:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:37:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:37:28 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'] 07:37:28 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 07:37:28 INFO - (blobuploader) - INFO - Open directory for files ... 07:37:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:37:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:37:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:37:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:37:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:37:30 INFO - (blobuploader) - INFO - Done attempting. 07:37:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:37:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:37:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:37:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:37:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:37:31 INFO - (blobuploader) - INFO - Done attempting. 07:37:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:37:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:37:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:37:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:37:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:37:33 INFO - (blobuploader) - INFO - Done attempting. 07:37:33 INFO - (blobuploader) - INFO - Iteration through files over. 07:37:33 INFO - Return code: 0 07:37:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:37:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:37:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa2cafbe34905e0d8f846c13ef7cac6f262f63c3552c32968c91a65070c4c8d1cd0789e95289f8d7d5a5d2822cdaa78064d27b7b5240be04087673cd5ac05f07", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c82e23234efad4d1779605abf2920324d27efd7e8bbd94665f71b31a51d8cfcef41f395d1281784b5498eaf16d2d19cba83f364d2f1f5863634d831edc0d0ff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/48aaeb917428ac2a93eb1fcf9e6afe497dcd6006267874d533a35f640573811f85d62206c37907b4f064c20bb4c563f4b9e80dfc820fc48ad8003b7cbf079982"} 07:37:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:37:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:37:33 INFO - Contents: 07:37:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa2cafbe34905e0d8f846c13ef7cac6f262f63c3552c32968c91a65070c4c8d1cd0789e95289f8d7d5a5d2822cdaa78064d27b7b5240be04087673cd5ac05f07", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c82e23234efad4d1779605abf2920324d27efd7e8bbd94665f71b31a51d8cfcef41f395d1281784b5498eaf16d2d19cba83f364d2f1f5863634d831edc0d0ff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/48aaeb917428ac2a93eb1fcf9e6afe497dcd6006267874d533a35f640573811f85d62206c37907b4f064c20bb4c563f4b9e80dfc820fc48ad8003b7cbf079982"} 07:37:33 INFO - Running post-run listener: copy_logs_to_upload_dir 07:37:33 INFO - Copying logs to upload dir... 07:37:33 INFO - mkdir: /builds/slave/test/build/upload/logs 07:37:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.269301 ========= master_lag: 2.48 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-20 07:37:35.722131) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-20 07:37:35.725191) ========= 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.KwQeDX2Oh6/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.VBwREEQ9rg/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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/fa2cafbe34905e0d8f846c13ef7cac6f262f63c3552c32968c91a65070c4c8d1cd0789e95289f8d7d5a5d2822cdaa78064d27b7b5240be04087673cd5ac05f07", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c82e23234efad4d1779605abf2920324d27efd7e8bbd94665f71b31a51d8cfcef41f395d1281784b5498eaf16d2d19cba83f364d2f1f5863634d831edc0d0ff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/48aaeb917428ac2a93eb1fcf9e6afe497dcd6006267874d533a35f640573811f85d62206c37907b4f064c20bb4c563f4b9e80dfc820fc48ad8003b7cbf079982"} build_url:https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011270 build_url: 'https://queue.taskcluster.net/v1/task/dYy4JNgpQAigjUlidRUsSA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa2cafbe34905e0d8f846c13ef7cac6f262f63c3552c32968c91a65070c4c8d1cd0789e95289f8d7d5a5d2822cdaa78064d27b7b5240be04087673cd5ac05f07", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c82e23234efad4d1779605abf2920324d27efd7e8bbd94665f71b31a51d8cfcef41f395d1281784b5498eaf16d2d19cba83f364d2f1f5863634d831edc0d0ff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/48aaeb917428ac2a93eb1fcf9e6afe497dcd6006267874d533a35f640573811f85d62206c37907b4f064c20bb4c563f4b9e80dfc820fc48ad8003b7cbf079982"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-20 07:37:35.758840) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-20 07:37:35.759179) ========= 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.KwQeDX2Oh6/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.VBwREEQ9rg/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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.005271 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-20 07:37:35.807661) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-20 07:37:35.807983) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-20 07:37:35.808296) ========= ========= Total master_lag: 2.62 =========