builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0141 starttime: 1480047326.28 results: success (0) buildid: 20161124173718 builduid: 9c5affca91414a84a8e80688aa0114a6 revision: 8ecafae107fbb8856948513301e2a726256b19d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-24 20:15:26.288827) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-24 20:15:26.289546) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-24 20:15:26.290012) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-24 20:15:26.605074) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 20:15:26.605540) ========= 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.QAceOpEwPL/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.Tl0xDhPGKF/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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-24 20:15:26-- 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% 49.6M=0s 2016-11-24 20:15:27 (49.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.610135 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 20:15:27.303815) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 20:15:27.304211) ========= 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.QAceOpEwPL/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.Tl0xDhPGKF/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.019361 ========= master_lag: 0.10 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 20:15:27.427617) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-24 20:15:27.428037) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 8ecafae107fbb8856948513301e2a726256b19d8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 8ecafae107fbb8856948513301e2a726256b19d8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QAceOpEwPL/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.Tl0xDhPGKF/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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-24 20:15:27,526 truncating revision to first 12 chars 2016-11-24 20:15:27,526 Setting DEBUG logging. 2016-11-24 20:15:27,526 attempt 1/10 2016-11-24 20:15:27,526 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/8ecafae107fb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-24 20:15:28,503 unpacking tar archive at: graphics-8ecafae107fb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.233756 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-24 20:15:28.707085) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-24 20:15:28.707410) ========= script_repo_revision: 8ecafae107fbb8856948513301e2a726256b19d8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-24 20:15:28.707815) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-24 20:15:28.708131) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-24 20:15:28.740602) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-24 20:15:28.740982) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QAceOpEwPL/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.Tl0xDhPGKF/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 20:15:28 INFO - MultiFileLogger online at 20161124 20:15:28 in /builds/slave/test 20:15:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 20:15:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:15:28 INFO - {'append_to_log': False, 20:15:28 INFO - 'base_work_dir': '/builds/slave/test', 20:15:28 INFO - 'blob_upload_branch': 'Graphics', 20:15:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:15:28 INFO - 'branch': 'Graphics', 20:15:28 INFO - 'buildbot_json_path': 'buildprops.json', 20:15:28 INFO - 'config_files': ('talos/mac_config.py',), 20:15:28 INFO - 'default_actions': ('clobber', 20:15:28 INFO - 'read-buildbot-config', 20:15:28 INFO - 'download-and-extract', 20:15:28 INFO - 'populate-webroot', 20:15:28 INFO - 'create-virtualenv', 20:15:28 INFO - 'install', 20:15:28 INFO - 'run-tests'), 20:15:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:15:28 INFO - 'download_minidump_stackwalk': True, 20:15:28 INFO - 'download_symbols': 'ondemand', 20:15:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:15:28 INFO - 'tooltool.py': '/tools/tooltool.py', 20:15:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:15:28 INFO - '/tools/misc-python/virtualenv.py')}, 20:15:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:15:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:15:28 INFO - 'installer_path': 'installer.exe', 20:15:28 INFO - 'log_level': 'info', 20:15:28 INFO - 'log_name': 'talos', 20:15:28 INFO - 'log_to_console': True, 20:15:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:15:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:15:28 INFO - 'opt_config_files': (), 20:15:28 INFO - 'pip_index': False, 20:15:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:15:28 INFO - 'cmd': ('bash', 20:15:28 INFO - '-c', 20:15:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:15:28 INFO - 'enabled': True, 20:15:28 INFO - 'halt_on_failure': False, 20:15:28 INFO - 'name': 'check_screen_resolution'},), 20:15:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:15:28 INFO - 'cmd': ('bash', 20:15:28 INFO - '-c', 20:15:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:15:28 INFO - 'enabled': True, 20:15:28 INFO - 'halt_on_failure': False, 20:15:28 INFO - 'name': 'check_screen_resolution'},), 20:15:28 INFO - 'run_cmd_checks_enabled': True, 20:15:28 INFO - 'sps_profile': False, 20:15:28 INFO - 'sps_profile_interval': 0, 20:15:28 INFO - 'suite': 'dromaeojs-e10s', 20:15:28 INFO - 'system_bits': '32', 20:15:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:15:28 INFO - 'title': 't-yosemite-r7-0141', 20:15:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:15:28 INFO - 'use_talos_json': True, 20:15:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:15:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:15:28 INFO - 'work_dir': 'build'} 20:15:28 INFO - [mozharness: 2016-11-25 04:15:28.940123Z] Running clobber step. 20:15:28 INFO - Running pre-action listener: _resource_record_pre_action 20:15:28 INFO - Running main action method: clobber 20:15:28 INFO - rmtree: /builds/slave/test/build 20:15:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:15:30 INFO - Running post-action listener: _resource_record_post_action 20:15:30 INFO - [mozharness: 2016-11-25 04:15:30.525025Z] Finished clobber step (success) 20:15:30 INFO - [mozharness: 2016-11-25 04:15:30.525150Z] Running read-buildbot-config step. 20:15:30 INFO - Running pre-action listener: _resource_record_pre_action 20:15:30 INFO - Running main action method: read_buildbot_config 20:15:30 INFO - Using buildbot properties: 20:15:30 INFO - { 20:15:30 INFO - "project": "", 20:15:30 INFO - "product": "firefox", 20:15:30 INFO - "script_repo_revision": "default", 20:15:30 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 20:15:30 INFO - "repository": "", 20:15:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 20:15:30 INFO - "buildid": "20161124173718", 20:15:30 INFO - "pgo_build": "False", 20:15:30 INFO - "basedir": "/builds/slave/test", 20:15:30 INFO - "buildnumber": 8, 20:15:30 INFO - "slavename": "t-yosemite-r7-0141", 20:15:30 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 20:15:30 INFO - "platform": "yosemite_r7", 20:15:30 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 20:15:30 INFO - "branch": "graphics", 20:15:30 INFO - "repo_path": "projects/graphics", 20:15:30 INFO - "revision": "8ecafae107fbb8856948513301e2a726256b19d8", 20:15:30 INFO - "stage_platform": "macosx64", 20:15:30 INFO - "builduid": "9c5affca91414a84a8e80688aa0114a6", 20:15:30 INFO - "slavebuilddir": "test" 20:15:30 INFO - } 20:15:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 20:15:30 INFO - Running post-action listener: _resource_record_post_action 20:15:30 INFO - [mozharness: 2016-11-25 04:15:30.528423Z] Finished read-buildbot-config step (success) 20:15:30 INFO - [mozharness: 2016-11-25 04:15:30.528558Z] Running download-and-extract step. 20:15:30 INFO - Running pre-action listener: _resource_record_pre_action 20:15:30 INFO - Running main action method: download_and_extract 20:15:30 INFO - mkdir: /builds/slave/test/build/tests 20:15:30 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')]} 20:15:30 INFO - trying https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 20:15:30 INFO - Downloading https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/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 20:15:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/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 20:15:31 INFO - Downloaded 1199 bytes. 20:15:31 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 20:15:31 INFO - Using the following test package requirements: 20:15:31 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:15:31 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:15:31 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 20:15:31 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:15:31 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 20:15:31 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:15:31 INFO - u'jsshell-mac.zip'], 20:15:31 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:15:31 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 20:15:31 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:15:31 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:15:31 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 20:15:31 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:15:31 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 20:15:31 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:15:31 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 20:15:31 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:15:31 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 20:15:31 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:15:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:15:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:15:31 INFO - Fetch https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:15:32 INFO - Http code: 200 20:15:32 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 20:15:32 INFO - x-amz-cf-id: 7ec3AHg2OKjVPh5tsfC8uq2CuV41ChfpLVUxGwYXjAnDgxlTTh0ohw== 20:15:32 INFO - x-amz-version-id: QZ7FvEL3qnT4iiptBQGNL1rfLnV2wTVX 20:15:32 INFO - Expected file size: 16267195 20:15:32 INFO - Obtained file size: 16267195 20:15:39 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 20:15:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:15:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:15:39 INFO - Fetch https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:15:40 INFO - Http code: 200 20:15:40 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 20:15:40 INFO - x-amz-cf-id: dlpssdCvyTrNZOEcJ5T6z7aRwJmReszOPiVfS2uWAeJbtWKUkan9AA== 20:15:40 INFO - x-amz-version-id: QZ7FvEL3qnT4iiptBQGNL1rfLnV2wTVX 20:15:40 INFO - Expected file size: 16267195 20:15:40 INFO - Obtained file size: 16267195 20:15:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 20:15:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:15:46 INFO - Fetch https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 20:15:47 INFO - Http code: 200 20:15:47 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 20:15:47 INFO - x-amz-cf-id: S1MEkYeNvXbU-HDnlHXFsHZZs5I8akwPb3AovPFYSg4jworlErGekA== 20:15:47 INFO - x-amz-version-id: 6yorp1zeZSaZikkUTb32Dmen1ei.3NwY 20:15:48 INFO - Expected file size: 14409506 20:15:48 INFO - Obtained file size: 14409506 20:15:48 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')]} 20:15:48 INFO - trying https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:15:48 INFO - Downloading https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 20:15:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/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 20:15:51 INFO - Downloaded 107554723 bytes. 20:15:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:15:51 INFO - mkdir: /builds/slave/test/properties 20:15:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:15:51 INFO - Writing to file /builds/slave/test/properties/build_url 20:15:51 INFO - Contents: 20:15:51 INFO - build_url:https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:15:52 INFO - Running post-action listener: _resource_record_post_action 20:15:52 INFO - Running post-action listener: set_extra_try_arguments 20:15:52 INFO - [mozharness: 2016-11-25 04:15:52.603331Z] Finished download-and-extract step (success) 20:15:52 INFO - [mozharness: 2016-11-25 04:15:52.603471Z] Running populate-webroot step. 20:15:52 INFO - Running pre-action listener: _resource_record_pre_action 20:15:52 INFO - Running main action method: populate_webroot 20:15:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:15:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:15:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:15:52 INFO - '--tppagecycles', 20:15:52 INFO - '7'], 20:15:52 INFO - 'tests': ['tsvgm']}}, 20:15:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:15:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:15:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:15:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:15:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:15:52 INFO - 'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:15:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:15:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:15:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:15:52 INFO - 'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['damp', 'tps']}, 20:15:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:15:52 INFO - 'tests': ['damp', 'tps']}, 20:15:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['dromaeo_dom']}, 20:15:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:15:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['basic_compositor_video']}, 20:15:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:15:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['a11yr', 20:15:52 INFO - 'ts_paint', 20:15:52 INFO - 'tpaint', 20:15:52 INFO - 'sessionrestore', 20:15:52 INFO - 'sessionrestore_no_auto_restore', 20:15:52 INFO - 'tabpaint']}, 20:15:52 INFO - 'other-e10s': {'tests': ['a11yr', 20:15:52 INFO - 'ts_paint', 20:15:52 INFO - 'tpaint', 20:15:52 INFO - 'sessionrestore', 20:15:52 INFO - 'sessionrestore_no_auto_restore', 20:15:52 INFO - 'tabpaint']}, 20:15:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:15:52 INFO - 'ts_paint', 20:15:52 INFO - 'tpaint', 20:15:52 INFO - 'sessionrestore', 20:15:52 INFO - 'sessionrestore_no_auto_restore', 20:15:52 INFO - 'tabpaint']}, 20:15:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:15:52 INFO - 'ts_paint', 20:15:52 INFO - 'tpaint', 20:15:52 INFO - 'sessionrestore', 20:15:52 INFO - 'sessionrestore_no_auto_restore', 20:15:52 INFO - 'tabpaint']}, 20:15:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['a11yr', 20:15:52 INFO - 'ts_paint', 20:15:52 INFO - 'tpaint', 20:15:52 INFO - 'sessionrestore', 20:15:52 INFO - 'sessionrestore_no_auto_restore', 20:15:52 INFO - 'tabpaint']}, 20:15:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['a11yr', 20:15:52 INFO - 'ts_paint', 20:15:52 INFO - 'tpaint', 20:15:52 INFO - 'sessionrestore', 20:15:52 INFO - 'sessionrestore_no_auto_restore', 20:15:52 INFO - 'tabpaint']}, 20:15:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['tsvgx', 20:15:52 INFO - 'tsvgr_opacity', 20:15:52 INFO - 'tart', 20:15:52 INFO - 'tscrollx', 20:15:52 INFO - 'cart']}, 20:15:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:15:52 INFO - 'tsvgr_opacity', 20:15:52 INFO - 'tart', 20:15:52 INFO - 'tscrollx', 20:15:52 INFO - 'cart']}, 20:15:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:15:52 INFO - 'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['tp5o']}, 20:15:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:15:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:15:52 INFO - 'talos_options': ['--disable-e10s', 20:15:52 INFO - '--xperf_path', 20:15:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:15:52 INFO - 'tests': ['tp5n']}, 20:15:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:15:52 INFO - 'talos_options': ['--xperf_path', 20:15:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:15:52 INFO - 'tests': ['tp5n']}}, 20:15:52 INFO - 'talos.zip': {'path': '', 20:15:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:15:52 INFO - Running post-action listener: _resource_record_post_action 20:15:52 INFO - [mozharness: 2016-11-25 04:15:52.621535Z] Finished populate-webroot step (success) 20:15:52 INFO - [mozharness: 2016-11-25 04:15:52.621666Z] Running create-virtualenv step. 20:15:52 INFO - Running pre-action listener: _resource_record_pre_action 20:15:52 INFO - Running main action method: create_virtualenv 20:15:52 INFO - Creating virtualenv /builds/slave/test/build/venv 20:15:52 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 20:15:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:15:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:15:52 INFO - Using real prefix '/tools/python27' 20:15:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:15:53 INFO - Installing distribute.............................................................................................................................................................................................done. 20:15:56 INFO - Installing pip.................done. 20:15:56 INFO - Return code: 0 20:15:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:15:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:15:56 INFO - Reading from file tmpfile_stdout 20:15:56 INFO - Output received: 20:15:56 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) 20:15:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:15:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:15:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:15:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:56 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 0x106cb33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c29570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa91cad4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ac9d30>, '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 0x106930db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ac1b20>, '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.QAceOpEwPL/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.Tl0xDhPGKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 20:15:56 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 20:15:56 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 20:15:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QAceOpEwPL/Render', 20:15:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:15:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:15:56 INFO - 'HOME': '/Users/cltbld', 20:15:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:15:56 INFO - 'LOGNAME': 'cltbld', 20:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:15:56 INFO - 'MOZ_NO_REMOTE': '1', 20:15:56 INFO - 'NO_EM_RESTART': '1', 20:15:56 INFO - 'PAGER': '/bin/cat', 20:15:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:15:56 INFO - 'PWD': '/builds/slave/test', 20:15:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:15:56 INFO - 'SHELL': '/bin/bash', 20:15:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tl0xDhPGKF/Listeners', 20:15:56 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 20:15:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:15:56 INFO - 'USER': 'cltbld', 20:15:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:15:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:15:56 INFO - 'XPC_FLAGS': '0x0', 20:15:56 INFO - 'XPC_SERVICE_NAME': '0', 20:15:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:15:56 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 20:15:56 INFO - Cleaning up... 20:15:56 INFO - Return code: 0 20:15:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:15:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:15:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:15:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:56 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 0x106cb33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c29570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa91cad4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ac9d30>, '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 0x106930db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ac1b20>, '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.QAceOpEwPL/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.Tl0xDhPGKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 20:15:56 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 20:15:56 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 20:15:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QAceOpEwPL/Render', 20:15:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:15:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:15:56 INFO - 'HOME': '/Users/cltbld', 20:15:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:15:56 INFO - 'LOGNAME': 'cltbld', 20:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:15:56 INFO - 'MOZ_NO_REMOTE': '1', 20:15:56 INFO - 'NO_EM_RESTART': '1', 20:15:56 INFO - 'PAGER': '/bin/cat', 20:15:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:15:56 INFO - 'PWD': '/builds/slave/test', 20:15:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:15:56 INFO - 'SHELL': '/bin/bash', 20:15:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tl0xDhPGKF/Listeners', 20:15:56 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 20:15:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:15:56 INFO - 'USER': 'cltbld', 20:15:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:15:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:15:56 INFO - 'XPC_FLAGS': '0x0', 20:15:56 INFO - 'XPC_SERVICE_NAME': '0', 20:15:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:15:57 INFO - Downloading/unpacking psutil>=3.1.1 20:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:16:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:16:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:16:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:16:00 INFO - Installing collected packages: psutil 20:16:00 INFO - Running setup.py install for psutil 20:16:01 INFO - building 'psutil._psutil_osx' extension 20:16:01 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 20:16:01 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 20:16:01 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 20:16:01 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 20:16:01 INFO - building 'psutil._psutil_posix' extension 20:16:01 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 20:16:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:16:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:16:01 INFO - ^ 20:16:01 INFO - 1 warning generated. 20:16:01 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 20:16:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:16:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:16:01 INFO - Successfully installed psutil 20:16:01 INFO - Cleaning up... 20:16:01 INFO - Return code: 0 20:16:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:16:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106cb33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c29570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa91cad4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ac9d30>, '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 0x106930db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ac1b20>, '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.QAceOpEwPL/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.Tl0xDhPGKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 20:16:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:16:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QAceOpEwPL/Render', 20:16:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:16:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:16:01 INFO - 'HOME': '/Users/cltbld', 20:16:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:16:01 INFO - 'LOGNAME': 'cltbld', 20:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:01 INFO - 'MOZ_NO_REMOTE': '1', 20:16:01 INFO - 'NO_EM_RESTART': '1', 20:16:01 INFO - 'PAGER': '/bin/cat', 20:16:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:01 INFO - 'PWD': '/builds/slave/test', 20:16:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:16:01 INFO - 'SHELL': '/bin/bash', 20:16:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tl0xDhPGKF/Listeners', 20:16:01 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 20:16:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:16:01 INFO - 'USER': 'cltbld', 20:16:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:16:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:01 INFO - 'XPC_FLAGS': '0x0', 20:16:01 INFO - 'XPC_SERVICE_NAME': '0', 20:16:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:16:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:16:02 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 20:16:02 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 20:16:02 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 20:16:02 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 20:16:02 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 20:16:02 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 20:16:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:16:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:16:04 INFO - Installing collected packages: mozsystemmonitor 20:16:04 INFO - Running setup.py install for mozsystemmonitor 20:16:04 INFO - Successfully installed mozsystemmonitor 20:16:04 INFO - Cleaning up... 20:16:04 INFO - Return code: 0 20:16:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:16:04 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')]} 20:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:04 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')]} 20:16:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:04 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 0x106cb33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c29570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa91cad4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ac9d30>, '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 0x106930db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ac1b20>, '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.QAceOpEwPL/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.Tl0xDhPGKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 20:16:04 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 20:16:04 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 20:16:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QAceOpEwPL/Render', 20:16:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:16:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:16:04 INFO - 'HOME': '/Users/cltbld', 20:16:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:16:04 INFO - 'LOGNAME': 'cltbld', 20:16:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:04 INFO - 'MOZ_NO_REMOTE': '1', 20:16:04 INFO - 'NO_EM_RESTART': '1', 20:16:04 INFO - 'PAGER': '/bin/cat', 20:16:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:16:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:04 INFO - 'PWD': '/builds/slave/test', 20:16:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:16:04 INFO - 'SHELL': '/bin/bash', 20:16:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tl0xDhPGKF/Listeners', 20:16:04 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 20:16:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:16:04 INFO - 'USER': 'cltbld', 20:16:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:16:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:16:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:04 INFO - 'XPC_FLAGS': '0x0', 20:16:04 INFO - 'XPC_SERVICE_NAME': '0', 20:16:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:16:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:05 INFO - Downloading/unpacking jsonschema==2.5.1 20:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:16:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:16:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:16:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:16:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:16:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:16:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:16:07 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 20:16:07 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 20:16:07 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 20:16:07 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 20:16:07 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 20:16:07 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 20:16:08 INFO - Downloading functools32-3.2.3-2.tar.gz 20:16:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:16:08 INFO - warning: no files found matching '*.txt' 20:16:08 INFO - no previously-included directories found matching 'build' 20:16:08 INFO - no previously-included directories found matching 'dist' 20:16:08 INFO - no previously-included directories found matching '.git*' 20:16:08 INFO - Installing collected packages: jsonschema, functools32 20:16:08 INFO - Running setup.py install for functools32 20:16:08 INFO - warning: no files found matching '*.txt' 20:16:08 INFO - no previously-included directories found matching 'build' 20:16:08 INFO - no previously-included directories found matching 'dist' 20:16:08 INFO - no previously-included directories found matching '.git*' 20:16:08 INFO - Successfully installed jsonschema functools32 20:16:08 INFO - Cleaning up... 20:16:08 INFO - Return code: 0 20:16:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:16:08 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')]} 20:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:08 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')]} 20:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:08 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 0x106cb33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c29570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa91cad4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ac9d30>, '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 0x106930db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ac1b20>, '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.QAceOpEwPL/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.Tl0xDhPGKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 20:16:08 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 20:16:08 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 20:16:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QAceOpEwPL/Render', 20:16:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:16:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:16:08 INFO - 'HOME': '/Users/cltbld', 20:16:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:16:08 INFO - 'LOGNAME': 'cltbld', 20:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:08 INFO - 'MOZ_NO_REMOTE': '1', 20:16:08 INFO - 'NO_EM_RESTART': '1', 20:16:08 INFO - 'PAGER': '/bin/cat', 20:16:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:08 INFO - 'PWD': '/builds/slave/test', 20:16:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:16:08 INFO - 'SHELL': '/bin/bash', 20:16:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tl0xDhPGKF/Listeners', 20:16:08 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 20:16:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:16:08 INFO - 'USER': 'cltbld', 20:16:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:16:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:08 INFO - 'XPC_FLAGS': '0x0', 20:16:08 INFO - 'XPC_SERVICE_NAME': '0', 20:16:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:16:08 INFO - Cleaning up... 20:16:08 INFO - Return code: 0 20:16:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:16:08 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')]} 20:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:08 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')]} 20:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:08 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 0x106cb33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c29570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa91cad4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ac9d30>, '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 0x106930db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ac1b20>, '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.QAceOpEwPL/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.Tl0xDhPGKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 20:16:08 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 20:16:08 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 20:16:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QAceOpEwPL/Render', 20:16:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:16:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:16:08 INFO - 'HOME': '/Users/cltbld', 20:16:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:16:08 INFO - 'LOGNAME': 'cltbld', 20:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:08 INFO - 'MOZ_NO_REMOTE': '1', 20:16:08 INFO - 'NO_EM_RESTART': '1', 20:16:08 INFO - 'PAGER': '/bin/cat', 20:16:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:08 INFO - 'PWD': '/builds/slave/test', 20:16:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:16:08 INFO - 'SHELL': '/bin/bash', 20:16:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tl0xDhPGKF/Listeners', 20:16:08 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 20:16:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:16:08 INFO - 'USER': 'cltbld', 20:16:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:16:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:08 INFO - 'XPC_FLAGS': '0x0', 20:16:08 INFO - 'XPC_SERVICE_NAME': '0', 20:16:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:08 INFO - Downloading/unpacking blobuploader==1.2.4 20:16:08 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 20:16:08 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 20:16:08 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 20:16:08 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 20:16:08 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 20:16:08 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 20:16:11 INFO - Downloading blobuploader-1.2.4.tar.gz 20:16:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:16:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:16:11 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 20:16:11 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 20:16:11 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 20:16:11 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 20:16:11 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 20:16:11 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 20:16:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:16:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:16:12 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 20:16:12 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 20:16:12 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 20:16:12 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 20:16:12 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 20:16:12 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 20:16:12 INFO - Downloading docopt-0.6.1.tar.gz 20:16:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:16:12 INFO - Installing collected packages: blobuploader, requests, docopt 20:16:12 INFO - Running setup.py install for blobuploader 20:16:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:16:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:16:12 INFO - Running setup.py install for requests 20:16:13 INFO - Running setup.py install for docopt 20:16:13 INFO - Successfully installed blobuploader requests docopt 20:16:13 INFO - Cleaning up... 20:16:13 INFO - Return code: 0 20:16:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:16: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')]} 20:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16: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')]} 20:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:13 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 0x106cb33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c29570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa91cad4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ac9d30>, '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 0x106930db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ac1b20>, '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.QAceOpEwPL/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.Tl0xDhPGKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 20:16:13 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 20:16:13 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 20:16:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QAceOpEwPL/Render', 20:16:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:16:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:16:13 INFO - 'HOME': '/Users/cltbld', 20:16:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:16:13 INFO - 'LOGNAME': 'cltbld', 20:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:13 INFO - 'MOZ_NO_REMOTE': '1', 20:16:13 INFO - 'NO_EM_RESTART': '1', 20:16:13 INFO - 'PAGER': '/bin/cat', 20:16:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:13 INFO - 'PWD': '/builds/slave/test', 20:16:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:16:13 INFO - 'SHELL': '/bin/bash', 20:16:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tl0xDhPGKF/Listeners', 20:16:13 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 20:16:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:16:13 INFO - 'USER': 'cltbld', 20:16:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:16:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:13 INFO - 'XPC_FLAGS': '0x0', 20:16:13 INFO - 'XPC_SERVICE_NAME': '0', 20:16:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:16:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-bumCcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:16:14 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-sip5iV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:16:14 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-BxZWmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:16:14 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-1ZcF1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:16:14 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-SkK2mC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:16:14 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-ORSIfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:16:14 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-9Yy7NO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:16:14 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-u398kd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:16:14 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-QWSeGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:16:14 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-KIOqG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:16:14 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-L9JslS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:16:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-2YcU1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:16:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-yE1DDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:16:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-HCtAGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:16:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-txW0IG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:16:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-JkjrYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:16:15 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-ENLTcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:16:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:16:15 INFO - Running setup.py install for manifestparser 20:16:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:16:15 INFO - Running setup.py install for mozcrash 20:16:15 INFO - Running setup.py install for mozdebug 20:16:16 INFO - Running setup.py install for mozdevice 20:16:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:16:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:16:16 INFO - Running setup.py install for mozfile 20:16:16 INFO - Running setup.py install for mozhttpd 20:16:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:16:16 INFO - Running setup.py install for mozinfo 20:16:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:16:16 INFO - Running setup.py install for mozInstall 20:16:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:16:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:16:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:16:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:16:16 INFO - Running setup.py install for mozleak 20:16:16 INFO - Running setup.py install for mozlog 20:16:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:16:17 INFO - Running setup.py install for moznetwork 20:16:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:16:17 INFO - Running setup.py install for mozprocess 20:16:17 INFO - Running setup.py install for mozprofile 20:16:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:16:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:16:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:16:17 INFO - Running setup.py install for mozrunner 20:16:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:16:17 INFO - Running setup.py install for mozscreenshot 20:16:17 INFO - Running setup.py install for moztest 20:16:18 INFO - Running setup.py install for mozversion 20:16:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:16:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:16:18 INFO - Cleaning up... 20:16:18 INFO - Return code: 0 20:16:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:16:18 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')]} 20:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:18 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')]} 20:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:18 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 0x106cb33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c29570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa91cad4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ac9d30>, '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 0x106930db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ac1b20>, '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.QAceOpEwPL/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.Tl0xDhPGKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 20:16:18 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 20:16:18 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 20:16:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QAceOpEwPL/Render', 20:16:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:16:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:16:18 INFO - 'HOME': '/Users/cltbld', 20:16:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:16:18 INFO - 'LOGNAME': 'cltbld', 20:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:18 INFO - 'MOZ_NO_REMOTE': '1', 20:16:18 INFO - 'NO_EM_RESTART': '1', 20:16:18 INFO - 'PAGER': '/bin/cat', 20:16:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:18 INFO - 'PWD': '/builds/slave/test', 20:16:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:16:18 INFO - 'SHELL': '/bin/bash', 20:16:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tl0xDhPGKF/Listeners', 20:16:18 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 20:16:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:16:18 INFO - 'USER': 'cltbld', 20:16:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:16:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:18 INFO - 'XPC_FLAGS': '0x0', 20:16:18 INFO - 'XPC_SERVICE_NAME': '0', 20:16:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:16:18 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-odIp5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:16:18 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)) 20:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:16:18 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-RWjyAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:16:18 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)) 20:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:16:18 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-CBpr4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:16:18 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)) 20:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:16:18 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-ICK3Ao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:16:18 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)) 20:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:16:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-LKcvCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:16:19 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)) 20:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:16:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-xM4gZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:16:19 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)) 20:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:16:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-_NWI2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:16:19 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)) 20:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:16:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-qDHUld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:16:19 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)) 20:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:16:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-6fj7a8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:16:19 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)) 20:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:16:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-s07MKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:16:19 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)) 20:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:16:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-CD8l5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:16:19 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)) 20:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:16:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-pjFN6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:16:19 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)) 20:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:16:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-ZM7SxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:16:19 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)) 20:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:16:19 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-POTm1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:16:20 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)) 20:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:16:20 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-BWuHlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:16:20 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)) 20:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:16:20 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-AfefHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:16:20 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)) 20:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:16:20 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-7lJrnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:16:20 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)) 20:16:20 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)) 20:16:20 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)) 20:16:20 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)) 20:16:20 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)) 20:16:20 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)) 20:16:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:16:20 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 20:16:20 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 20:16:20 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 20:16:20 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 20:16:20 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 20:16:20 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 20:16:22 INFO - Downloading blessings-1.6.tar.gz 20:16:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:16:23 INFO - Installing collected packages: blessings 20:16:23 INFO - Running setup.py install for blessings 20:16:23 INFO - Successfully installed blessings 20:16:23 INFO - Cleaning up... 20:16:23 INFO - Return code: 0 20:16:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:16:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:16:23 INFO - Reading from file tmpfile_stdout 20:16:23 INFO - Current package versions: 20:16:23 INFO - blessings == 1.6 20:16:23 INFO - blobuploader == 1.2.4 20:16:23 INFO - docopt == 0.6.1 20:16:23 INFO - functools32 == 3.2.3-2 20:16:23 INFO - jsonschema == 2.5.1 20:16:23 INFO - manifestparser == 1.1 20:16:23 INFO - mozInstall == 1.12 20:16:23 INFO - mozcrash == 1.0 20:16:23 INFO - mozdebug == 0.1 20:16:23 INFO - mozdevice == 0.49 20:16:23 INFO - mozfile == 1.2 20:16:23 INFO - mozhttpd == 0.7 20:16:23 INFO - mozinfo == 0.9 20:16:23 INFO - mozleak == 0.1 20:16:23 INFO - mozlog == 3.4 20:16:23 INFO - moznetwork == 0.27 20:16:23 INFO - mozprocess == 0.23 20:16:23 INFO - mozprofile == 0.28 20:16:23 INFO - mozrunner == 6.13 20:16:23 INFO - mozscreenshot == 0.1 20:16:23 INFO - mozsystemmonitor == 0.3 20:16:23 INFO - moztest == 0.7 20:16:23 INFO - mozversion == 1.4 20:16:23 INFO - psutil == 3.1.1 20:16:23 INFO - requests == 1.2.3 20:16:23 INFO - wsgiref == 0.1.2 20:16:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:16:23 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')]} 20:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:23 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')]} 20:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:23 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 0x106cb33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c29570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa91cad4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ac9d30>, '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 0x106930db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ac1b20>, '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.QAceOpEwPL/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.Tl0xDhPGKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 20:16:23 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 20:16:23 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 20:16:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QAceOpEwPL/Render', 20:16:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:16:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:16:23 INFO - 'HOME': '/Users/cltbld', 20:16:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:16:23 INFO - 'LOGNAME': 'cltbld', 20:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:23 INFO - 'MOZ_NO_REMOTE': '1', 20:16:23 INFO - 'NO_EM_RESTART': '1', 20:16:23 INFO - 'PAGER': '/bin/cat', 20:16:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:23 INFO - 'PWD': '/builds/slave/test', 20:16:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:16:23 INFO - 'SHELL': '/bin/bash', 20:16:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tl0xDhPGKF/Listeners', 20:16:23 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 20:16:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:16:23 INFO - 'USER': 'cltbld', 20:16:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:16:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:23 INFO - 'XPC_FLAGS': '0x0', 20:16:23 INFO - 'XPC_SERVICE_NAME': '0', 20:16:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:23 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)) 20:16:23 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)) 20:16:23 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)) 20:16:23 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)) 20:16:23 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)) 20:16:23 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)) 20:16:23 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)) 20:16:23 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)) 20:16:23 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)) 20:16:23 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)) 20:16:23 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)) 20:16:23 INFO - Cleaning up... 20:16:23 INFO - Return code: 0 20:16:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:16:23 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')]} 20:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:23 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')]} 20:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:23 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 0x106cb33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c29570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa91cad4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ac9d30>, '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 0x106930db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ac1b20>, '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.QAceOpEwPL/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.Tl0xDhPGKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 20:16:23 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 20:16:23 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 20:16:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QAceOpEwPL/Render', 20:16:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:16:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:16:23 INFO - 'HOME': '/Users/cltbld', 20:16:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:16:23 INFO - 'LOGNAME': 'cltbld', 20:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:23 INFO - 'MOZ_NO_REMOTE': '1', 20:16:23 INFO - 'NO_EM_RESTART': '1', 20:16:23 INFO - 'PAGER': '/bin/cat', 20:16:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:23 INFO - 'PWD': '/builds/slave/test', 20:16:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:16:23 INFO - 'SHELL': '/bin/bash', 20:16:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tl0xDhPGKF/Listeners', 20:16:23 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 20:16:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:16:23 INFO - 'USER': 'cltbld', 20:16:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:16:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:23 INFO - 'XPC_FLAGS': '0x0', 20:16:23 INFO - 'XPC_SERVICE_NAME': '0', 20:16:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:16:24 INFO - Cleaning up... 20:16:24 INFO - Return code: 0 20:16:24 INFO - Running post-action listener: _resource_record_post_action 20:16:24 INFO - Running post-action listener: _start_resource_monitoring 20:16:24 INFO - Starting resource monitoring. 20:16:24 INFO - [mozharness: 2016-11-25 04:16:24.112237Z] Finished create-virtualenv step (success) 20:16:24 INFO - [mozharness: 2016-11-25 04:16:24.113278Z] Running install step. 20:16:24 INFO - Running pre-action listener: _resource_record_pre_action 20:16:24 INFO - Running main action method: install 20:16:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:16:24 INFO - Reading from file tmpfile_stdout 20:16:24 INFO - Detecting whether we're running mozinstall >=1.0... 20:16:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:16:24 INFO - Reading from file tmpfile_stdout 20:16:24 INFO - Output received: 20:16:24 INFO - Usage: mozinstall [options] installer 20:16:24 INFO - Options: 20:16:24 INFO - -h, --help show this help message and exit 20:16:24 INFO - -d DEST, --destination=DEST 20:16:24 INFO - Directory to install application into. [default: 20:16:24 INFO - "/builds/slave/test"] 20:16:24 INFO - --app=APP Application being installed. [default: firefox] 20:16:24 INFO - mkdir: /builds/slave/test/build/application 20:16:24 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'] 20:16:24 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 20:16:57 INFO - Reading from file tmpfile_stdout 20:16:57 INFO - Output received: 20:16:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:16:57 INFO - Running post-action listener: _resource_record_post_action 20:16:57 INFO - [mozharness: 2016-11-25 04:16:57.402277Z] Finished install step (success) 20:16:57 INFO - [mozharness: 2016-11-25 04:16:57.402422Z] Running run-tests step. 20:16:57 INFO - Running pre-action listener: _resource_record_pre_action 20:16:57 INFO - Running main action method: run_tests 20:16:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:16:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:16:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:16:57 INFO - 2016-11-24 20:16:57.416 screenresolution[2177:21947] starting screenresolution argv=screenresolution get 20:16:57 INFO - 2016-11-24 20:16:57.419 screenresolution[2177:21947] Display 0: 1600x1200x32@60 20:16:57 INFO - 2016-11-24 20:16:57.425 screenresolution[2178:21949] starting screenresolution argv=screenresolution list 20:16:57 INFO - Available Modes on Display 0 20:16:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:16:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:16:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:16:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:16:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:16:57 INFO - Intel Iris: 20:16:57 INFO - Chipset Model: Intel Iris 20:16:57 INFO - Type: GPU 20:16:57 INFO - Bus: Built-In 20:16:57 INFO - VRAM (Dynamic, Max): 1536 MB 20:16:57 INFO - Vendor: Intel (0x8086) 20:16:57 INFO - Device ID: 0x0a2e 20:16:57 INFO - Revision ID: 0x0009 20:16:57 INFO - Displays: 20:16:57 INFO - Display: 20:16:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:16:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:16:57 INFO - Display Serial Number: 1600x1200 60 20:16:57 INFO - Main Display: Yes 20:16:57 INFO - Mirror: Off 20:16:57 INFO - Online: Yes 20:16:57 INFO - Rotation: Supported 20:16:57 INFO - Return code: 0 20:16:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:16:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:16:57 INFO - Python 2.7.3 20:16:57 INFO - Return code: 0 20:16:57 INFO - grabbing minidump binary from tooltool 20: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')]} 20:16:57 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 0x106ac9d30>, '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 0x106930db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ac1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:16:57 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 20:16:57 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 20:16:57 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 20:16:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:16:57 INFO - Return code: 0 20:16:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:16:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:16:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:16:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:16:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:16:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--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 20:16:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0141 --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 20:16:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QAceOpEwPL/Render', 20:16:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:16:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:16:57 INFO - 'HOME': '/Users/cltbld', 20:16:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:16:57 INFO - 'LOGNAME': 'cltbld', 20:16:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:16:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:57 INFO - 'MOZ_NO_REMOTE': '1', 20:16:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:16:57 INFO - 'NO_EM_RESTART': '1', 20:16:57 INFO - 'PAGER': '/bin/cat', 20:16:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:57 INFO - 'PWD': '/builds/slave/test', 20:16:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:16:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:16:57 INFO - 'SHELL': '/bin/bash', 20:16:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tl0xDhPGKF/Listeners', 20:16:57 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 20:16:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:16:57 INFO - 'USER': 'cltbld', 20:16:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:16:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:57 INFO - 'XPC_FLAGS': '0x0', 20:16:57 INFO - 'XPC_SERVICE_NAME': '0', 20:16:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:16:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--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 20:16:58 INFO - mozversion application_buildid: 20161124173718 20:16:58 INFO - mozversion application_changeset: 8ecafae107fbb8856948513301e2a726256b19d8 20:16:58 INFO - mozversion application_display_name: Nightly 20:16:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:16:58 INFO - mozversion application_name: Firefox 20:16:58 INFO - mozversion application_remotingname: firefox 20:16:58 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 20:16:58 INFO - mozversion application_vendor: Mozilla 20:16:58 INFO - mozversion application_version: 53.0a1 20:16:58 INFO - mozversion platform_buildid: 20161124173718 20:16:58 INFO - mozversion platform_changeset: 8ecafae107fbb8856948513301e2a726256b19d8 20:16:58 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 20:16:58 INFO - mozversion platform_version: 53.0a1 20:16:58 INFO - using testdate: 1480047418 20:16:58 INFO - actual date: 1480047418 20:16:58 INFO - starting webserver on 'localhost:53929' 20:16:58 INFO - SUITE-START | Running 2 tests 20:16:58 INFO - TEST-START | dromaeo_css 20:16:58 INFO - operating with platform_type : mac_ 20:16:58 INFO - Initialising browser for dromaeo_css test... 20:16:58 INFO - TEST-INFO | started process 2188 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmp78Gzsm/profile http://localhost:53929/getInfo.html) 20:17:01 INFO - PROCESS | 2188 | 2016-11-24 20:17:01.016 plugin-container[2190:22105] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4853, name = 'com.apple.tsm.portname' 20:17:01 INFO - PROCESS | 2188 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:17:01 INFO - PROCESS | 2188 | 2016-11-24 20:17:01.019 plugin-container[2190:22105] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 20:17:01 INFO - PROCESS | 2188 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:17:01 INFO - PROCESS | 2188 | 2016-11-24 20:17:01.019 plugin-container[2190:22105] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:17:01 INFO - PROCESS | 2188 | __metrics Screen width/height:1600/1200 20:17:01 INFO - PROCESS | 2188 | colorDepth:24 20:17:01 INFO - PROCESS | 2188 | Browser inner width/height: 1024/689 20:17:01 INFO - PROCESS | 2188 | __metrics 20:17:01 ERROR - PROCESS | 2188 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 20:17:01 INFO - TEST-INFO | 2188: exit 0 20:17:01 INFO - Browser initialized. 20:17:01 INFO - Running cycle 1/1 for dromaeo_css test... 20:17:01 INFO - TEST-INFO | started process 2191 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmp78Gzsm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:17:04 INFO - PROCESS | 2191 | 2016-11-24 20:17:04.615 plugin-container[2192:22233] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 20:17:04 INFO - PROCESS | 2191 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:17:04 INFO - PROCESS | 2191 | 2016-11-24 20:17:04.616 plugin-container[2192:22233] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 20:17:04 INFO - PROCESS | 2191 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:17:04 INFO - PROCESS | 2191 | 2016-11-24 20:17:04.616 plugin-container[2192:22233] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:17:14 INFO - PROCESS | 2191 | RSS: Main: 241594368 20:17:14 INFO - PROCESS | 2191 | 20:18:03 INFO - PROCESS | 2191 | 1480047483965 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] 20:20:17 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:53929/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53929/tests/dromaeo/cssquery-ext.html) 20:20:18 INFO - PROCESS | 2191 | RSS: Main: 255377408 20:20:18 INFO - PROCESS | 2191 | 20:23:21 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:53929/tests/dromaeo/cssquery-ext.html (next: http://localhost:53929/tests/dromaeo/cssquery-jquery.html) 20:23:21 INFO - PROCESS | 2191 | RSS: Main: 264294400 20:23:21 INFO - PROCESS | 2191 | 20:26:23 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:53929/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53929/tests/dromaeo/cssquery-mootools.html) 20:26:24 INFO - PROCESS | 2191 | RSS: Main: 269004800 20:26:24 INFO - PROCESS | 2191 | 20:29:26 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:53929/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53929/tests/dromaeo/cssquery-prototype.html) 20:29:27 INFO - PROCESS | 2191 | RSS: Main: 268410880 20:29:27 INFO - PROCESS | 2191 | 20:32:29 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:53929/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53929/tests/dromaeo/cssquery-yui.html) 20:32:30 INFO - PROCESS | 2191 | RSS: Main: 272859136 20:32:30 INFO - PROCESS | 2191 | 20:35:33 INFO - PROCESS | 2191 | Cycle 1(1): loaded http://localhost:53929/tests/dromaeo/cssquery-yui.html (next: http://localhost:53929/tests/dromaeo/cssquery-dojo.html) 20:35:33 INFO - PROCESS | 2191 | RSS: Main: 276496384 20:35:33 INFO - PROCESS | 2191 | 20:35:33 INFO - PROCESS | 2191 | __start_tp_report 20:35:33 INFO - PROCESS | 2191 | _x_x_mozilla_page_load 20:35:33 INFO - PROCESS | 2191 | _x_x_mozilla_page_load_details 20:35:33 INFO - PROCESS | 2191 | |i|pagename|runs| 20:35:33 INFO - PROCESS | 2191 | |0;dojo.html;3052.816830990142;3132.592762940818;3374.409478341291;3429.1941393772477;3462.982685086575;4233.597808208219;4425;4627.8380256691025;5010.874728131798;5848;5780.4219578042175;5782.855428614287;5842.766289348431;5854.297484301879;5873.324567674718;4277.652539450079;4406.206882761102;4854.781534830923;4996.300517927493;5338.332708411449;5225.954809038189;5318.042752304584;5344.732763361836;5356.464353564632;5376.193570964348;4285.614294713475;4337.587929146726;5258.421573626898;5322.148456247001;5382.515573598375;5211.348581427322;5296.761645725952;5318.521333080042;5366.9463305366835;5396.271503347057;5149.253358263069;5190.688558686491;5226.451222621608;5249.790008399659;5251.52736253739;4872.415310162768;5395;5726.627769194989;5729.484346408802;5814.273215848019;6189.535784816157;6242;6252.468540174091;6264.1543391642;6293.118963345135;6161.7227224775;6216.937830621681;6253.96873015632;6261.530385221126;6292.244930608356;5710.172025056344;5748;5756.856078598027;5792.855178620568;5862.14998825168;6226.844328891814;6288.465480434214;6289.40250676185;6293.496520278367;6322.841928951813;5991.7902873399225;6094.299155597163;6106.175666284995;6109.5112391008615;6135;6151.954167791486;6166.383361663798;6200.348963358872;6237.313895471494;6238.75044998195;6202.906956395657;6223.875522489525;6272.717727702265;6296.307406185316;6297.716602752887;6103.761967186988;6138.1738283688655;6146.3404880681965;6201.658908760061;6275.748970041147;4116.897077573085;4288.485381754147;4975.477574854661;4992.101421744048;5047.97476012625;4380.036391993756;4397.494288156839;4467.888302792456;4468;4508.954910450853;4747.786349614275;5965.492933100648;6010.128531362928;6018.00702884028;6018.668973206516;6160.045192995093;6269.529785266123;6327.746890124436;6332.430081292706;6341.270753863272;5858.677772722541;5872.911906321408;5887.793927212527;5897.705114744245;5915.201447804491;5481.397046324901;5489.505944465017;5493.368262649847;5516.862078447991;5527.834164975057;5333.386660534088;6430.260520040255;6447.742090316428;6457.2896981332015;6468.773592924225;6135.477655820289;6149.754009839557;6209.292444577777;6249.96875015622;6251.6874156292915;5782.681952492653;6007.768407476544;6382.393672601095;6473.943595430877;6523.3150519194805;6192.845178870564;6206.472449841814;6219.626822390671;6232.719527621177;6280.780172693934;4937.975310123426;5090.727318170458;6234.127222188807;6235.158253635701;6280.842978925563;6016.217891674054;6125.601746185282;6130.581587308013;6164.8767024658355;6165.445109940127;5210.817621383233;6174.66039367839;6231.626102433868;6249.750009999549;6258.186435763321;5089.541941225308;5828.388019257917;5933.495652869555;5977.641341519523;5983.880322393615;6192.97815860378;6225.719842607185;6248.468880145057;6264.154339164154;6272.655003974825;5928.170056192051;5990.4908082813445;6113.052476866093;6145.985912324416;6146.661933593696 20:35:33 INFO - PROCESS | 2191 | |1;ext.html;450.08857064444516;460.16250424227917;460.6038806626301;514.4212760644275;530.9097453432918;29116.854415727918;38050.238995220076;38058;38110.04724881872;38226.04434889129;39694.603053969455;40057.799711001404;40196.79901600488;40279.59720402795;40282.59717402825;7468.327850493454;8595.742127736177;9238.722838314845;9439.669611563597;9462.1484066434;10931.45342732864;11260.887391126065;11294.435278236097;11383.772324553503;11431.657050288504;11271.774564508705;11294;11356.602518911839;11366.147538934572;11384.772304553904;11142.721431964144;11194.720131996684;11256.437178141101;11297.491612877358;11348.297755336765;10967.067799237075;11041.39272340021;11064.28082174656;11083.778324433466;11154.944225278821;38037.23925521502;38218.80890595557;38490.422643660364;38497;38503.422448663296;3271.770976031679;3323.7174840138377;3375.679310465526;3388.440907250301;3449.0859922120408;25176.49647007068;29191.70808291911;30156.54765178524;30204.0938771835;30850.691493085007;3200.983995080033;3227.1770698471973;3235.9352812943607;3241.092494101655;3316.8507417166043;383661;484017;489601;493963;495913;63588.68205658942;64507.677461612395;64539;64567.677161613894;64685.70628587402;66843.66578167175;66872.33127668661;67245;67314.66342668256;67458;15526.611834703997;15995.920020400057;16009.599760006093;16018.679626407407;16080.597985050468;10787.244892857581;10954.561817527368;12062.97464715509;12158.209716368408;12326.876731232753;19385.70064655288;19422.70865937012;19492.610147797248;19528.70706939397;19555.217791288473;7776.533407995538;8213.89112469809;8249.29880960125;8415.158484151658;8434.831303374021;731.5062332925361;961.8028304197622;964.363520076746;1028.6142696488816;1051.7116532248087;24704.752952470604;28374;28715.682549839003;29338;29362.412751744847;874.9868751968726;1003.6487229469773;1005.1255407795119;1034.8447732840134;1062.0972173652333;13702;14608.707825843636;14854.70290594182;15062.472813451477;15063.397464101314;656349;661709;669400;669514;672061;42892.78553607212;52637.47362526402;52739.472605274226;53220;53279.93440131176;1151.6142092398973;1339.216558313378;1373.8145350377772;1376.6558360409697;1391.512970460351;24060.87969560141;28427.573586395807;28471.572926406534;28572.85713571419;29388.412231754817;45763.31355029611;46297.07405851931;46556.53443465522;46631;46727.29909051435;552833;553281;557916;557928;558022;32886.84896028723;36709;39073.804630976665;39080.41379379368;39526.60473395229;40116.59883401245;42885.785571071945;42962.78518607387;43545;43722.34416483692;36250.27499450049;36314.273714525036;36436.635633644386;37102.628973709914;37712.62287377091;35784;35836.64163358333;36965.075873103386;37025;37063.629363706015;34558.13604659904;34940.650593494756;35250.94247172589;35381;35775.64224357723;27300.59049114225;29530.852345738134;32155;32266;32504.83747581247;24393.02427902935;24464.877675611508;24478.75521244836;24569.631455527822;24736.752632474167 20:35:33 INFO - PROCESS | 2191 | |2;jquery.html;346.52526039326114;369.52331492374833;379.70193398182425;382.6154714511915;401.22563452536593;18691.71962420563;19337.903310483463;19346.51633709156;20085.59828803425;20250.696239556382;11332.660020199386;11760.000399966013;11769.117316201276;11782.528698852035;11803.173777835533;5030.647854650176;5319.840404787863;5381.488758567949;5430.15832545956;5438.265834112403;3879.5538513071074;4090.836366545335;4841.757912104398;5311.1502159654465;5356.1697936819855;4096.5698601646845;5847.1521629363715;6501.284858665566;6591.406773390394;6670.366315200073;5765.538756899438;6038.849028774316;6589.505787065966;6629.138212032429;6637.701303441357;5701.173329867188;5746.36789953109;5758.337791154029;5762.308522977228;5779.450952159538;12523.311217883012;21371.893140534354;24475.26574202777;24511.38721531976;24518.754812451825;2147.4146977058836;2164.0802658869916;2253.6281513550407;2261;2271.954560908789;9689.32174747768;10207.234457415723;10347.327423717434;12353.135280530367;14431.567052988426;1766.6378392429522;1817.872748907577;1818.4908225696827;1827.8080801515916;1843.7050071988551;69145.99999999949;93942;102066.46900296501;112890;119600;8530.402871799039;10121.493925303705;11458.828117578243;11506;11553.768924621629;11681.357525336018;14413.49552765669;14438.855611443962;14474.565763027125;14501.709965800836;8221.095679475253;8364.749057528285;8400.285975692135;8413.747587572383;8564.657413703506;6725.831854203586;6797.184337879387;8246.54643994586;8248.030708944774;8385.245327920395;3326.7690954347045;4368.475782906072;4439.955600444019;4467.642588592905;4475.664325175625;1520.635047588593;1805.9705967598607;1889.782674992366;1930.835878950305;1941.8737782044122;802.5144787403653;861.7587075618836;894.8299823033733;952.4142652268805;978.280963491833;18080.276788928295;18098;18849.34027309065;21189.78810211909;21249;888.8311220868014;931.65063101337;954.7088138117801;965.9275554333312;967.7919247361851;10685.038346548692;11307.717307067225;11452.828207576893;11709.707257318636;11736.354500502553;506023;506346;507209;532406;551011;76057.6197119022;77624.83562746564;78649.60675196587;119178;131562;830.2569200565581;845.8392905347964;929.6327950459531;954.513198268874;989.6684610655367;16679.332826686797;18848.62302753965;19209.423717288504;19284.13221405068;19314.903425482782;36520;41829.372559412244;53911.382658520306;54480.727596361765;55807.72096139493;50607;53262.40212793622;60870.69564652148;76257.23742762502;80762;10763.300385474919;11155.107591392507;11322.94338528302;11534;11767;11286.717832054264;11378.31730096197;12507.74984500323;12794.488220471078;12817.935910320388;10801.621943232263;11177.720556985814;11210;11212.719682008015;11224.831627525431;10701.678949631523;10900.618478353219;11027.83458248111;11314.208005439541;11325.377104259345;9053;9386.718398448058;11255.831162532404;11348.489317980875;11504.137189710973;9684.95157524208;11214.607488737855;11849.466773995364;11893.643190704293;11982.10134239918;10783.568657253936;10897.782044359226;11287.604933827277;11461.083113351078;11462.197646164688 20:35:33 INFO - PROCESS | 2191 | |3;mootools.html;1471.7277303698816;1490.2325302469228;1663.20997526175;1762.8237176282366;1790.6955817511014;7538.359239464652;7815.335696465807;8076.838463230732;8397.076321604618;8464.873026904594;7960.442769006172;8291.419600627958;8297.460665056768;8344.457610255344;8362;2264.682944387787;2283.771622837711;2294.8967296471615;2297.115610489965;2298.5977453945575;2295.5064661097863;2325.3953971967326;2337.2637619149914;2340.8361414700976;2341.016772955354;2278.2481781012225;2280.680704701343;2303.1248125712204;2303.550807592512;2310.260716570698;2260.7400148982915;2267.671187677794;2283.6232021716396;2285.6800047993293;2294.919677811277;2240.6639004149506;2285.3144056783067;2299.379167624749;2309.884505774721;2309.9720624322185;8279.089300176976;8303.294219991309;8359.874601880976;8363.707270245512;8393.076761556224;5004.9499505005215;5155.32980712505;5156.535911767922;5157.30376399185;5185.61107916904;3167.5407065975305;3242.951355729666;3469.5663042119736;3473.5484387029524;3473.548438702978;988.3180605382263;1023.5240613114937;1028.4908970059794;1028.8353863381824;1036.8496567997674;10667.759770448216;10673.946630266877;10674.306170098918;10828.40443775592;10831.67504974852;3645.65366290202;4571.748553829505;4649.721016739007;4668.95331046692;4677.579017888338;9143.222826059859;9258.259339252843;9272.907270927204;9277.304202184863;9279.118483744034;4361.520232774392;5106.131957567222;5128;5130.127878260705;5137.614678899097;4197.76912269822;4340.52254252032;4490.865274041784;4680.485146633868;4687.765611719468;3660.066682995819;3705.444183372516;3755.6619904208205;3805.2009078093356;3823.4264860270628;1248.4132457744845;1257.076049103908;1260.5021016698354;1267.8668739782374;1271.7392934448417;789.5302295134501;868.1708967935606;892.7455675132557;919.2324409118461;933.7758937854866;5454.809081682122;5579.2746942897975;5610.793679358876;5652.236948011966;5676.574256930747;859.3297228162044;877.9780505487414;898.3397203055749;988.9505524723879;994.2046362909643;3086.5987421635045;3346.8453383582623;3390.423627983249;3604.3151801157696;3709.2581483703366;9192.586333615003;9849.310548261554;9885.25860560461;9896.307258491837;9908.851367229498;9615.134637882624;9825.656102036395;9841.55712992917;9848.606055757833;9860.506974651382;7522.511036782592;7769.145394006655;7782.844343113106;7802.375809935192;7806.531608103532;5356.437574054824;5397;5414.539764120094;5454.645448045864;5486.890262194814;9007.819843603222;9028.954855225682;9058.682946096855;9128.315376346933;9183.54082295896;9192.816143677222;9211.723648290563;9254.722358329262;9357.251419886257;9367.812643747224;6267.96866015667;6459.321771213955;6554.344565543598;6633.2040155181685;6765.42493888025;7834.6866125354345;7931.762047138595;7951.284384405431;7952.36381089523;7976.162502937108;6931.896021559782;7053.400460960671;7089.716411343695;7111.964440177766;7198;6873.347032031949;6929.44564434834;6947.617881016593;7003.404710599656;7003.719851205895;6579.671016449254;6612.173478315211;6668.966655166693;6684.264730879502;6689.364510371507;4818.301346304836;4861.708297502161;4878.463369029333;4885.340479035285;4937.580305674058;4847.587955023721;4848.660593758403;4851.733154676526;4919.0899683558655;4943.431505376784 20:35:33 INFO - PROCESS | 2191 | |4;prototype.html;292.7935805257294;299.2190383100109;302.85311623862435;306.1656984716646;309.31949710636604;5106.055379754739;5277.182036784295;5307.416184219743;5419.674819510828;5481.643693159946;4024.396340548919;4932.078997197228;5071.6053085401445;5279.947200527993;5304.655197412166;3346.665333466652;4066.654334381581;4104.753714777108;4111.732737372061;4141.358089496133;3494.4408894576873;3907.960920390802;4025;4106.28140075487;4144.730592511477;4032.778197199153;4061.8375264989363;4066.9389959150635;4081.795910204493;4082.734622249559;3163.9525407118913;3456.8962931112105;3558.822058897045;3979.283728928792;4092.2812418783883;2804.088671181874;2926.590277361171;3060.8622611982514;3088.583041289442;3536.8231588420476;4183.205191013728;4656.813727450898;5162.793488260465;5201.245819356172;5227.79088836446;3842.7886466244345;3858.536975562922;4554.089182163548;4569.246074397721;4586.472555656109;5178.17149256121;5562.415946325618;5630.380658127644;5650.773969041234;5652.858678533028;3542.990247779396;3564.536610240652;3795.658390744846;3850.306944749944;3878.8448462061483;5400.432954539756;5417.241586177938;5609.046462101453;5795.507381872546;5874.357898103161;3392.49112633107;3848.807559622008;3866.4393662919283;3879.2241551689776;3880.4761357216416;3922.7254092213843;3925.391564307537;3925.568187499373;3930.331843586597;3949.2299001694396;3616.367135751233;4446.6220371268155;4568.33759104928;4583.587477127075;4607;4773.928391074137;5380.677159370451;5634.943650563524;5660.801871934545;5661.971690141523;1089.7929393415225;1114.7603265298;1202.705337192376;1217.500824661902;1276.5213045108085;763.3778470546487;873.5021038008274;905.2667339455063;908.68650315641;929.8233335666201;4319.408354987046;4938.802447902043;5018.2974383586325;5068.265101560327;5141.53726164647;5153.510416510426;5224.50367215114;5375.7849686012905;5547.362053363834;5581.944180558224;4040.5353384361174;4407.625351845129;4416.271315233015;4998.050370429607;5022.0620936751675;2426.4419183587875;2905.1575043237226;2921.8246905185756;2922.313256384748;2925.9122226333243;4230.830766769294;5727.828165155052;5729.598928074995;5897.375667827775;6146.508279337642;5985.700714964321;6091.299500557493;6103.786367477206;6104.542159338067;6146.692665366714;4721.929171062437;5837.445442682938;5976.308597750832;6177.4749146323065;6225.774225774226;5367.570594352364;5784.623999440023;5815.738291776795;5849.678267695318;5930.733117009831;5528.087865502147;5769.730659254958;5842.707864606838;5843.590948633725;5846.824595262149;5753.597248192586;5802.506786923159;5842.597776533687;5923.844850254159;5948.627758597341;3585.279066048259;3882.22355528901;3919.8040097994417;3922.6861851052417;3931.3709806430834;4967.354243948408;5184.948150518447;5865.677387743715;5903.409659034062;5915.497182739343;5369.6509726867625;5499.9925027365525;5598.580106491948;5665.167416291854;5777.422257774189;4772.236442169236;5656.773729050792;5687.971560142173;5705.315362156568;5719.370869204301;5200.531952124327;5359.812406565751;5451.618386712892;5475.890482190254;5617.74720137603;5240.4759524047295;5498.120300751861;5669.829905102853;5679.74441150141;5693.943060569507;5255.290535777621;5563.8887222256135;5596.216529685928;5614.070150894472;5618.887622247614 20:35:33 INFO - PROCESS | 2191 | |5;yui.html;636.6593872278332;644.5005121031201;645.4900628503483;663.5521023309268;740.4483659673544;10539.946005399455;11931.9261266486;13487.865121348796;13973.441062357506;14740.115593064424;11148.888511114887;13389.129706569067;13395.531156409528;13402.93298533506;13456.596302110927;596.5555661032525;820.2248874813304;836.1471299274737;836.8953880764905;837.543538771368;657.950653700972;680.3774546290139;815.4454970619976;837.3217693668148;845.8181490979438;876.3996662286322;885.8494056010494;885.995570022149;888.8622263549161;889.181952603603;871.6774793326479;872.5898827551041;874.6982291109557;875.951822649754;877.6182360673096;652.879217344788;653.0432915778407;653.555582204101;653.6208998780743;654.8002859127964;13648.181109133484;14323.641908952255;14348.354324055339;14366.712665746627;14367.78448323276;12718.300493472856;12944.417501212469;13018.15382000167;13195.8886578148;13297.933510332387;11184.217104802667;11234.719132021683;11294.322340659586;11379;11621.360825154614;3633.1098880774116;3633.7819730816236;3638.890833275006;3644.362236608583;3769.830357633886;93430.53284733601;93448;93815;94384.05615943822;94712;10540.63107791239;10542.736431589272;10582.629607963685;10649.893501065046;10882.782344353227;20471.4882127948;20491.4877128073;20492.540895092385;20503.17987280492;20520.973951302374;7517.210692877169;7584.5070070445245;7615.5430674159725;7649.286309413948;7802.882956755653;4237.131388065439;4254.510731265882;4322.891927701832;4323.308270676676;4360.149770794665;3327.750418718606;3373.983130084383;3391.7286617070577;3412.5904891412692;3420.572428446444;648.9188851393576;661.1570247933827;664.9734010639617;674.8582797612556;677.0994577212394;566.6909439195389;612.2469362683923;618.5422787137576;638.7604648256904;639.6449970266547;8636;8929.642814287487;8965.141666625037;8981.416207946462;9088.272938164931;598.3999522076571;627.9152314437583;638.4158494977044;664.6045602866287;683.1801837794666;4132.173565286955;4223.894402639897;4367.366731823868;4417.337399390117;4506.887327816765;106986.46506767416;107941.46029269803;115398;116338;117013.24480132805;101447.98552014533;104633.47683261687;104796;105402.94597054085;105676.47161764296;87051;87430.56284718536;87591;89057;90563.54718226499;8537.487750734976;8576.44253123545;8629.13708629132;8655.95672021661;8690.749426205171;43618.34572481352;52091.6977075561;55716;56160;57384.12234960075;69077;76437.23562764301;76999;77444;77755.44489110299;10981.61564345244;11050.33697978124;11104.278221915549;11105;11395.886041139815;11932.463039163085;11961.521539138685;11980.10149238828;12014.75970480603;12147.939260303641;8783.429077109968;11207.887921121011;11405.885941140814;11916;11972.605478904079;11354.943225283821;11595.586516898373;11789.823152652545;11892.286462812259;12116.818247726114;9267.76830579241;11746.119041071783;11962.461689223832;12084.577039803564;12091.637250882488;8668.266346730554;9058.50178240203;9222.170004699608;9827.895409553259;9926.652567160114;7532.585707786125;9059.954700226457;9417.764555886157;9980.900190997998;10031.347962382422 20:35:33 INFO - PROCESS | 2191 | __end_tp_report 20:35:33 INFO - PROCESS | 2191 | __start_cc_report 20:35:33 INFO - PROCESS | 2191 | _x_x_mozilla_cycle_collect,376 20:35:33 INFO - PROCESS | 2191 | __end_cc_report 20:35:33 INFO - PROCESS | 2191 | __startTimestamp1480048533827__endTimestamp 20:35:33 INFO - PROCESS | 2191 | 20:35:33 INFO - PROCESS | 2191 | ------- Summary: start ------- 20:35:33 INFO - PROCESS | 2191 | Number of tests: 6 20:35:33 INFO - PROCESS | 2191 | 20:35:33 INFO - PROCESS | 2191 | [#0] dojo.html Cycles:165 Average:5687.13 Median:5983.88 stddev:732.03 (12.2%) stddev-sans-first:704.50 20:35:33 INFO - PROCESS | 2191 | Values: 3052.8 3132.6 3374.4 3429.2 3463.0 4233.6 4425.0 4627.8 5010.9 5848.0 5780.4 5782.9 5842.8 5854.3 5873.3 4277.7 4406.2 4854.8 4996.3 5338.3 5226.0 5318.0 5344.7 5356.5 5376.2 4285.6 4337.6 5258.4 5322.1 5382.5 5211.3 5296.8 5318.5 5366.9 5396.3 5149.3 5190.7 5226.5 5249.8 5251.5 4872.4 5395.0 5726.6 5729.5 5814.3 6189.5 6242.0 6252.5 6264.2 6293.1 6161.7 6216.9 6254.0 6261.5 6292.2 5710.2 5748.0 5756.9 5792.9 5862.1 6226.8 6288.5 6289.4 6293.5 6322.8 5991.8 6094.3 6106.2 6109.5 6135.0 6152.0 6166.4 6200.3 6237.3 6238.8 6202.9 6223.9 6272.7 6296.3 6297.7 6103.8 6138.2 6146.3 6201.7 6275.7 4116.9 4288.5 4975.5 4992.1 5048.0 4380.0 4397.5 4467.9 4468.0 4509.0 4747.8 5965.5 6010.1 6018.0 6018.7 6160.0 6269.5 6327.7 6332.4 6341.3 5858.7 5872.9 5887.8 5897.7 5915.2 5481.4 5489.5 5493.4 5516.9 5527.8 5333.4 6430.3 6447.7 6457.3 6468.8 6135.5 6149.8 6209.3 6250.0 6251.7 5782.7 6007.8 6382.4 6473.9 6523.3 6192.8 6206.5 6219.6 6232.7 6280.8 4938.0 5090.7 6234.1 6235.2 6280.8 6016.2 6125.6 6130.6 6164.9 6165.4 5210.8 6174.7 6231.6 6249.8 6258.2 5089.5 5828.4 5933.5 5977.6 5983.9 6193.0 6225.7 6248.5 6264.2 6272.7 5928.2 5990.5 6113.1 6146.0 6146.7 20:35:33 INFO - PROCESS | 2191 | 20:35:33 INFO - PROCESS | 2191 | [#1] ext.html Cycles:180 Average:69382.63 Median:35315.97 stddev:152447.91 (431.7%) stddev-sans-first:152787.71 20:35:33 INFO - PROCESS | 2191 | Values: 450.1 460.2 460.6 514.4 530.9 29116.9 38050.2 38058.0 38110.0 38226.0 39694.6 40057.8 40196.8 40279.6 40282.6 7468.3 8595.7 9238.7 9439.7 9462.1 10931.5 11260.9 11294.4 11383.8 11431.7 11271.8 11294.0 11356.6 11366.1 11384.8 11142.7 11194.7 11256.4 11297.5 11348.3 10967.1 11041.4 11064.3 11083.8 11154.9 38037.2 38218.8 38490.4 38497.0 38503.4 3271.8 3323.7 3375.7 3388.4 3449.1 25176.5 29191.7 30156.5 30204.1 30850.7 3201.0 3227.2 3235.9 3241.1 3316.9 383661.0 484017.0 489601.0 493963.0 495913.0 63588.7 64507.7 64539.0 64567.7 64685.7 66843.7 66872.3 67245.0 67314.7 67458.0 15526.6 15995.9 16009.6 16018.7 16080.6 10787.2 10954.6 12063.0 12158.2 12326.9 19385.7 19422.7 19492.6 19528.7 19555.2 7776.5 8213.9 8249.3 8415.2 8434.8 731.5 961.8 964.4 1028.6 1051.7 24704.8 28374.0 28715.7 29338.0 29362.4 875.0 1003.6 1005.1 1034.8 1062.1 13702.0 14608.7 14854.7 15062.5 15063.4 656349.0 661709.0 669400.0 669514.0 672061.0 42892.8 52637.5 52739.5 53220.0 53279.9 1151.6 1339.2 1373.8 1376.7 1391.5 24060.9 28427.6 28471.6 28572.9 29388.4 45763.3 46297.1 46556.5 46631.0 46727.3 552833.0 553281.0 557916.0 557928.0 558022.0 32886.8 36709.0 39073.8 39080.4 39526.6 40116.6 42885.8 42962.8 43545.0 43722.3 36250.3 36314.3 36436.6 37102.6 37712.6 35784.0 35836.6 36965.1 37025.0 37063.6 34558.1 34940.7 35250.9 35381.0 35775.6 27300.6 29530.9 32155.0 32266.0 32504.8 24393.0 24464.9 24478.8 24569.6 24736.8 20:35:33 INFO - PROCESS | 2191 | 20:35:33 INFO - PROCESS | 2191 | [#2] jquery.html Cycles:180 Average:30660.02 Median:2266.48 stddev:86497.34 (3816.4%) stddev-sans-first:86710.04 20:35:33 INFO - PROCESS | 2191 | Values: 346.5 369.5 379.7 382.6 401.2 18691.7 19337.9 19346.5 20085.6 20250.7 11332.7 11760.0 11769.1 11782.5 11803.2 5030.6 5319.8 5381.5 5430.2 5438.3 3879.6 4090.8 4841.8 5311.2 5356.2 4096.6 5847.2 6501.3 6591.4 6670.4 5765.5 6038.8 6589.5 6629.1 6637.7 5701.2 5746.4 5758.3 5762.3 5779.5 12523.3 21371.9 24475.3 24511.4 24518.8 2147.4 2164.1 2253.6 2261.0 2272.0 9689.3 10207.2 10347.3 12353.1 14431.6 1766.6 1817.9 1818.5 1827.8 1843.7 69146.0 93942.0 102066.5 112890.0 119600.0 8530.4 10121.5 11458.8 11506.0 11553.8 11681.4 14413.5 14438.9 14474.6 14501.7 8221.1 8364.7 8400.3 8413.7 8564.7 6725.8 6797.2 8246.5 8248.0 8385.2 3326.8 4368.5 4440.0 4467.6 4475.7 1520.6 1806.0 1889.8 1930.8 1941.9 802.5 861.8 894.8 952.4 978.3 18080.3 18098.0 18849.3 21189.8 21249.0 888.8 931.7 954.7 965.9 967.8 10685.0 11307.7 11452.8 11709.7 11736.4 506023.0 506346.0 507209.0 532406.0 551011.0 76057.6 77624.8 78649.6 119178.0 131562.0 830.3 845.8 929.6 954.5 989.7 16679.3 18848.6 19209.4 19284.1 19314.9 36520.0 41829.4 53911.4 54480.7 55807.7 50607.0 53262.4 60870.7 76257.2 80762.0 10763.3 11155.1 11322.9 11534.0 11767.0 11286.7 11378.3 12507.7 12794.5 12817.9 10801.6 11177.7 11210.0 11212.7 11224.8 10701.7 10900.6 11027.8 11314.2 11325.4 9053.0 9386.7 11255.8 11348.5 11504.1 9685.0 11214.6 11849.5 11893.6 11982.1 10783.6 10897.8 11287.6 11461.1 11462.2 20:35:33 INFO - PROCESS | 2191 | 20:35:33 INFO - PROCESS | 2191 | [#3] mootools.html Cycles:180 Average:5359.92 Median:5405.77 stddev:2928.17 (54.2%) stddev-sans-first:2921.80 20:35:33 INFO - PROCESS | 2191 | Values: 1471.7 1490.2 1663.2 1762.8 1790.7 7538.4 7815.3 8076.8 8397.1 8464.9 7960.4 8291.4 8297.5 8344.5 8362.0 2264.7 2283.8 2294.9 2297.1 2298.6 2295.5 2325.4 2337.3 2340.8 2341.0 2278.2 2280.7 2303.1 2303.6 2310.3 2260.7 2267.7 2283.6 2285.7 2294.9 2240.7 2285.3 2299.4 2309.9 2310.0 8279.1 8303.3 8359.9 8363.7 8393.1 5004.9 5155.3 5156.5 5157.3 5185.6 3167.5 3243.0 3469.6 3473.5 3473.5 988.3 1023.5 1028.5 1028.8 1036.8 10667.8 10673.9 10674.3 10828.4 10831.7 3645.7 4571.7 4649.7 4669.0 4677.6 9143.2 9258.3 9272.9 9277.3 9279.1 4361.5 5106.1 5128.0 5130.1 5137.6 4197.8 4340.5 4490.9 4680.5 4687.8 3660.1 3705.4 3755.7 3805.2 3823.4 1248.4 1257.1 1260.5 1267.9 1271.7 789.5 868.2 892.7 919.2 933.8 5454.8 5579.3 5610.8 5652.2 5676.6 859.3 878.0 898.3 989.0 994.2 3086.6 3346.8 3390.4 3604.3 3709.3 9192.6 9849.3 9885.3 9896.3 9908.9 9615.1 9825.7 9841.6 9848.6 9860.5 7522.5 7769.1 7782.8 7802.4 7806.5 5356.4 5397.0 5414.5 5454.6 5486.9 9007.8 9029.0 9058.7 9128.3 9183.5 9192.8 9211.7 9254.7 9357.3 9367.8 6268.0 6459.3 6554.3 6633.2 6765.4 7834.7 7931.8 7951.3 7952.4 7976.2 6931.9 7053.4 7089.7 7112.0 7198.0 6873.3 6929.4 6947.6 7003.4 7003.7 6579.7 6612.2 6669.0 6684.3 6689.4 4818.3 4861.7 4878.5 4885.3 4937.6 4847.6 4848.7 4851.7 4919.1 4943.4 20:35:33 INFO - PROCESS | 2191 | 20:35:33 INFO - PROCESS | 2191 | [#4] prototype.html Cycles:180 Average:4508.43 Median:5147.52 stddev:1443.29 (28.0%) stddev-sans-first:1412.23 20:35:33 INFO - PROCESS | 2191 | Values: 292.8 299.2 302.9 306.2 309.3 5106.1 5277.2 5307.4 5419.7 5481.6 4024.4 4932.1 5071.6 5279.9 5304.7 3346.7 4066.7 4104.8 4111.7 4141.4 3494.4 3908.0 4025.0 4106.3 4144.7 4032.8 4061.8 4066.9 4081.8 4082.7 3164.0 3456.9 3558.8 3979.3 4092.3 2804.1 2926.6 3060.9 3088.6 3536.8 4183.2 4656.8 5162.8 5201.2 5227.8 3842.8 3858.5 4554.1 4569.2 4586.5 5178.2 5562.4 5630.4 5650.8 5652.9 3543.0 3564.5 3795.7 3850.3 3878.8 5400.4 5417.2 5609.0 5795.5 5874.4 3392.5 3848.8 3866.4 3879.2 3880.5 3922.7 3925.4 3925.6 3930.3 3949.2 3616.4 4446.6 4568.3 4583.6 4607.0 4773.9 5380.7 5634.9 5660.8 5662.0 1089.8 1114.8 1202.7 1217.5 1276.5 763.4 873.5 905.3 908.7 929.8 4319.4 4938.8 5018.3 5068.3 5141.5 5153.5 5224.5 5375.8 5547.4 5581.9 4040.5 4407.6 4416.3 4998.1 5022.1 2426.4 2905.2 2921.8 2922.3 2925.9 4230.8 5727.8 5729.6 5897.4 6146.5 5985.7 6091.3 6103.8 6104.5 6146.7 4721.9 5837.4 5976.3 6177.5 6225.8 5367.6 5784.6 5815.7 5849.7 5930.7 5528.1 5769.7 5842.7 5843.6 5846.8 5753.6 5802.5 5842.6 5923.8 5948.6 3585.3 3882.2 3919.8 3922.7 3931.4 4967.4 5184.9 5865.7 5903.4 5915.5 5369.7 5500.0 5598.6 5665.2 5777.4 4772.2 5656.8 5688.0 5705.3 5719.4 5200.5 5359.8 5451.6 5475.9 5617.7 5240.5 5498.1 5669.8 5679.7 5693.9 5255.3 5563.9 5596.2 5614.1 5618.9 20:35:33 INFO - PROCESS | 2191 | 20:35:33 INFO - PROCESS | 2191 | [#5] yui.html Cycles:180 Average:20747.24 Median:53903.85 stddev:31623.90 (58.7%) stddev-sans-first:31676.57 20:35:33 INFO - PROCESS | 2191 | Values: 636.7 644.5 645.5 663.6 740.4 10539.9 11931.9 13487.9 13973.4 14740.1 11148.9 13389.1 13395.5 13402.9 13456.6 596.6 820.2 836.1 836.9 837.5 658.0 680.4 815.4 837.3 845.8 876.4 885.8 886.0 888.9 889.2 871.7 872.6 874.7 876.0 877.6 652.9 653.0 653.6 653.6 654.8 13648.2 14323.6 14348.4 14366.7 14367.8 12718.3 12944.4 13018.2 13195.9 13297.9 11184.2 11234.7 11294.3 11379.0 11621.4 3633.1 3633.8 3638.9 3644.4 3769.8 93430.5 93448.0 93815.0 94384.1 94712.0 10540.6 10542.7 10582.6 10649.9 10882.8 20471.5 20491.5 20492.5 20503.2 20521.0 7517.2 7584.5 7615.5 7649.3 7802.9 4237.1 4254.5 4322.9 4323.3 4360.1 3327.8 3374.0 3391.7 3412.6 3420.6 648.9 661.2 665.0 674.9 677.1 566.7 612.2 618.5 638.8 639.6 8636.0 8929.6 8965.1 8981.4 9088.3 598.4 627.9 638.4 664.6 683.2 4132.2 4223.9 4367.4 4417.3 4506.9 106986.5 107941.5 115398.0 116338.0 117013.2 101448.0 104633.5 104796.0 105402.9 105676.5 87051.0 87430.6 87591.0 89057.0 90563.5 8537.5 8576.4 8629.1 8656.0 8690.7 43618.3 52091.7 55716.0 56160.0 57384.1 69077.0 76437.2 76999.0 77444.0 77755.4 10981.6 11050.3 11104.3 11105.0 11395.9 11932.5 11961.5 11980.1 12014.8 12147.9 8783.4 11207.9 11405.9 11916.0 11972.6 11354.9 11595.6 11789.8 11892.3 12116.8 9267.8 11746.1 11962.5 12084.6 12091.6 8668.3 9058.5 9222.2 9827.9 9926.7 7532.6 9060.0 9417.8 9980.9 10031.3 20:35:33 INFO - PROCESS | 2191 | -------- Summary: end -------- 20:35:33 INFO - PROCESS | 2191 | 20:35:34 INFO - TEST-INFO | 2191: exit 0 20:35:34 INFO - TEST-OK | dromaeo_css | took 1116453ms 20:35:34 INFO - TEST-START | kraken 20:35:34 INFO - operating with platform_type : mac_ 20:35:34 INFO - Initialising browser for kraken test... 20:35:34 INFO - TEST-INFO | started process 2197 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpKmhZpR/profile http://localhost:53929/getInfo.html) 20:35:37 INFO - PROCESS | 2197 | 2016-11-24 20:35:37.292 plugin-container[2198:24285] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 20:35:37 INFO - PROCESS | 2197 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:35:37 INFO - PROCESS | 2197 | 2016-11-24 20:35:37.296 plugin-container[2198:24285] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9017, name = 'com.apple.CFPasteboardClient' 20:35:37 INFO - PROCESS | 2197 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:35:37 INFO - PROCESS | 2197 | 2016-11-24 20:35:37.296 plugin-container[2198:24285] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:35:37 INFO - PROCESS | 2197 | __metrics Screen width/height:1600/1200 20:35:37 INFO - PROCESS | 2197 | colorDepth:24 20:35:37 INFO - PROCESS | 2197 | Browser inner width/height: 1024/689 20:35:37 INFO - PROCESS | 2197 | __metrics 20:35:37 ERROR - PROCESS | 2197 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 20:35:38 INFO - TEST-INFO | 2197: exit 0 20:35:38 INFO - Browser initialized. 20:35:38 INFO - Running cycle 1/1 for kraken test... 20:35:38 INFO - TEST-INFO | started process 2199 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpKmhZpR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:35:41 INFO - PROCESS | 2199 | 2016-11-24 20:35:41.007 plugin-container[2200:24405] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 20:35:41 INFO - PROCESS | 2199 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:35:41 INFO - PROCESS | 2199 | 2016-11-24 20:35:41.007 plugin-container[2200:24405] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 20:35:41 INFO - PROCESS | 2199 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:35:41 INFO - PROCESS | 2199 | 2016-11-24 20:35:41.008 plugin-container[2200:24405] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:35:50 INFO - PROCESS | 2199 | RSS: Main: 241147904 20:35:50 INFO - PROCESS | 2199 | 20:35:53 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:53929/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53929/tests/kraken/driver.html?testName=audio-beat-detection) 20:35:54 INFO - PROCESS | 2199 | RSS: Main: 253345792 20:35:54 INFO - PROCESS | 2199 | 20:35:56 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:53929/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53929/tests/kraken/driver.html?testName=audio-dft) 20:35:57 INFO - PROCESS | 2199 | RSS: Main: 261087232 20:35:57 INFO - PROCESS | 2199 | 20:36:00 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:53929/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53929/tests/kraken/driver.html?testName=audio-fft) 20:36:01 INFO - PROCESS | 2199 | RSS: Main: 263794688 20:36:01 INFO - PROCESS | 2199 | 20:36:03 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:53929/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53929/tests/kraken/driver.html?testName=audio-oscillator) 20:36:03 INFO - PROCESS | 2199 | RSS: Main: 265977856 20:36:03 INFO - PROCESS | 2199 | 20:36:05 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:53929/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53929/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:36:06 INFO - PROCESS | 2199 | RSS: Main: 266317824 20:36:06 INFO - PROCESS | 2199 | 20:36:09 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:53929/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53929/tests/kraken/driver.html?testName=imaging-darkroom) 20:36:10 INFO - PROCESS | 2199 | RSS: Main: 272678912 20:36:10 INFO - PROCESS | 2199 | 20:36:13 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:53929/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53929/tests/kraken/driver.html?testName=imaging-desaturate) 20:36:13 INFO - PROCESS | 2199 | RSS: Main: 272519168 20:36:13 INFO - PROCESS | 2199 | 20:36:16 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:53929/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53929/tests/kraken/driver.html?testName=json-parse-financial) 20:36:17 INFO - PROCESS | 2199 | RSS: Main: 271884288 20:36:17 INFO - PROCESS | 2199 | 20:36:18 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:53929/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53929/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:36:19 INFO - PROCESS | 2199 | RSS: Main: 270929920 20:36:19 INFO - PROCESS | 2199 | 20:36:21 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:53929/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53929/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:36:22 INFO - PROCESS | 2199 | RSS: Main: 271044608 20:36:22 INFO - PROCESS | 2199 | 20:36:24 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:53929/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53929/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:36:25 INFO - PROCESS | 2199 | RSS: Main: 270966784 20:36:25 INFO - PROCESS | 2199 | 20:36:28 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:53929/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53929/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:36:28 INFO - PROCESS | 2199 | RSS: Main: 271400960 20:36:28 INFO - PROCESS | 2199 | 20:36:31 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:53929/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53929/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:36:32 INFO - PROCESS | 2199 | RSS: Main: 271642624 20:36:32 INFO - PROCESS | 2199 | 20:36:34 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:53929/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53929/tests/kraken/driver.html?testName=ai-astar) 20:36:34 INFO - PROCESS | 2199 | RSS: Main: 273498112 20:36:34 INFO - PROCESS | 2199 | 20:36:34 INFO - PROCESS | 2199 | __start_tp_report 20:36:34 INFO - PROCESS | 2199 | _x_x_mozilla_page_load 20:36:34 INFO - PROCESS | 2199 | _x_x_mozilla_page_load_details 20:36:34 INFO - PROCESS | 2199 | |i|pagename|runs| 20:36:34 INFO - PROCESS | 2199 | |0;ai-astar;109;119;117;117;119;118;116;117;118;118 20:36:34 INFO - PROCESS | 2199 | |1;audio-beat-detection;149;103;149;189;149;115;119;116;135;115 20:36:34 INFO - PROCESS | 2199 | |2;audio-dft;195;198;211;231;200;198;200;196;199;181 20:36:34 INFO - PROCESS | 2199 | |3;audio-fft;89;60;61;85;60;62;72;51;81;83 20:36:34 INFO - PROCESS | 2199 | |4;audio-oscillator;86;72;83;71;70;70;80;69;72;105 20:36:34 INFO - PROCESS | 2199 | |5;imaging-gaussian-blur;114;132;89;87;90;85;89;86;90;90 20:36:34 INFO - PROCESS | 2199 | |6;imaging-darkroom;99;101;100;99;109;98;145;176;99;99 20:36:34 INFO - PROCESS | 2199 | |7;imaging-desaturate;94;95;92;91;135;93;92;92;96;93 20:36:34 INFO - PROCESS | 2199 | |8;json-parse-financial;56;58;56;56;64;74;86;89;87;89 20:36:34 INFO - PROCESS | 2199 | |9;json-stringify-tinderbox;70;74;82;85;76;71;70;72;74;72 20:36:34 INFO - PROCESS | 2199 | |10;stanford-crypto-aes;91;89;91;90;87;91;91;94;109;112 20:36:34 INFO - PROCESS | 2199 | |11;stanford-crypto-ccm;150;149;150;151;153;153;153;179;237;199 20:36:34 INFO - PROCESS | 2199 | |12;stanford-crypto-pbkdf2;178;178;173;205;244;192;211;177;178;173 20:36:34 INFO - PROCESS | 2199 | |13;stanford-crypto-sha256-iterative;72;86;95;69;65;72;69;68;69;69 20:36:34 INFO - PROCESS | 2199 | __end_tp_report 20:36:34 INFO - PROCESS | 2199 | __start_cc_report 20:36:34 INFO - PROCESS | 2199 | _x_x_mozilla_cycle_collect,939 20:36:34 INFO - PROCESS | 2199 | __end_cc_report 20:36:34 INFO - PROCESS | 2199 | __startTimestamp1480048594626__endTimestamp 20:36:34 INFO - PROCESS | 2199 | 20:36:34 INFO - PROCESS | 2199 | ------- Summary: start ------- 20:36:34 INFO - PROCESS | 2199 | Number of tests: 14 20:36:34 INFO - PROCESS | 2199 | 20:36:34 INFO - PROCESS | 2199 | [#0] ai-astar Cycles:10 Average:116.80 Median:118.00 stddev:2.90 (2.5%) stddev-sans-first:1.00 20:36:34 INFO - PROCESS | 2199 | Values: 109.0 119.0 117.0 117.0 119.0 118.0 116.0 117.0 118.0 118.0 20:36:34 INFO - PROCESS | 2199 | 20:36:34 INFO - PROCESS | 2199 | [#1] audio-beat-detection Cycles:10 Average:133.90 Median:142.00 stddev:25.68 (18.1%) stddev-sans-first:26.64 20:36:34 INFO - PROCESS | 2199 | Values: 149.0 103.0 149.0 189.0 149.0 115.0 119.0 116.0 135.0 115.0 20:36:34 INFO - PROCESS | 2199 | 20:36:34 INFO - PROCESS | 2199 | [#2] audio-dft Cycles:10 Average:200.90 Median:199.50 stddev:12.84 (6.4%) stddev-sans-first:13.45 20:36:34 INFO - PROCESS | 2199 | Values: 195.0 198.0 211.0 231.0 200.0 198.0 200.0 196.0 199.0 181.0 20:36:34 INFO - PROCESS | 2199 | 20:36:34 INFO - PROCESS | 2199 | [#3] audio-fft Cycles:10 Average:70.40 Median:76.50 stddev:13.27 (17.3%) stddev-sans-first:12.25 20:36:34 INFO - PROCESS | 2199 | Values: 89.0 60.0 61.0 85.0 60.0 62.0 72.0 51.0 81.0 83.0 20:36:34 INFO - PROCESS | 2199 | 20:36:34 INFO - PROCESS | 2199 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:72.00 stddev:11.31 (15.7%) stddev-sans-first:11.60 20:36:34 INFO - PROCESS | 2199 | Values: 86.0 72.0 83.0 71.0 70.0 70.0 80.0 69.0 72.0 105.0 20:36:34 INFO - PROCESS | 2199 | 20:36:34 INFO - PROCESS | 2199 | [#5] imaging-gaussian-blur Cycles:10 Average:95.20 Median:89.00 stddev:15.35 (17.3%) stddev-sans-first:14.70 20:36:34 INFO - PROCESS | 2199 | Values: 114.0 132.0 89.0 87.0 90.0 85.0 89.0 86.0 90.0 90.0 20:36:34 INFO - PROCESS | 2199 | 20:36:34 INFO - PROCESS | 2199 | [#6] imaging-darkroom Cycles:10 Average:112.50 Median:98.50 stddev:26.52 (26.9%) stddev-sans-first:27.67 20:36:34 INFO - PROCESS | 2199 | Values: 99.0 101.0 100.0 99.0 109.0 98.0 145.0 176.0 99.0 99.0 20:36:34 INFO - PROCESS | 2199 | 20:36:34 INFO - PROCESS | 2199 | [#7] imaging-desaturate Cycles:10 Average:97.30 Median:93.00 stddev:13.33 (14.3%) stddev-sans-first:14.09 20:36:34 INFO - PROCESS | 2199 | Values: 94.0 95.0 92.0 91.0 135.0 93.0 92.0 92.0 96.0 93.0 20:36:34 INFO - PROCESS | 2199 | 20:36:34 INFO - PROCESS | 2199 | [#8] json-parse-financial Cycles:10 Average:71.50 Median:80.00 stddev:15.01 (18.8%) stddev-sans-first:14.84 20:36:34 INFO - PROCESS | 2199 | Values: 56.0 58.0 56.0 56.0 64.0 74.0 86.0 89.0 87.0 89.0 20:36:34 INFO - PROCESS | 2199 | 20:36:34 INFO - PROCESS | 2199 | [#9] json-stringify-tinderbox Cycles:10 Average:74.60 Median:74.00 stddev:5.10 (6.9%) stddev-sans-first:5.13 20:36:34 INFO - PROCESS | 2199 | Values: 70.0 74.0 82.0 85.0 76.0 71.0 70.0 72.0 74.0 72.0 20:36:34 INFO - PROCESS | 2199 | 20:36:34 INFO - PROCESS | 2199 | [#10] stanford-crypto-aes Cycles:10 Average:94.50 Median:91.00 stddev:8.64 (9.5%) stddev-sans-first:9.08 20:36:34 INFO - PROCESS | 2199 | Values: 91.0 89.0 91.0 90.0 87.0 91.0 91.0 94.0 109.0 112.0 20:36:34 INFO - PROCESS | 2199 | 20:36:34 INFO - PROCESS | 2199 | [#11] stanford-crypto-ccm Cycles:10 Average:167.40 Median:153.00 stddev:29.46 (19.3%) stddev-sans-first:30.57 20:36:34 INFO - PROCESS | 2199 | Values: 150.0 149.0 150.0 151.0 153.0 153.0 153.0 179.0 237.0 199.0 20:36:34 INFO - PROCESS | 2199 | 20:36:34 INFO - PROCESS | 2199 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.90 Median:185.00 stddev:22.99 (12.4%) stddev-sans-first:23.91 20:36:34 INFO - PROCESS | 2199 | Values: 178.0 178.0 173.0 205.0 244.0 192.0 211.0 177.0 178.0 173.0 20:36:34 INFO - PROCESS | 2199 | 20:36:34 INFO - PROCESS | 2199 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.40 Median:70.50 stddev:9.47 (13.4%) stddev-sans-first:10.03 20:36:34 INFO - PROCESS | 2199 | Values: 72.0 86.0 95.0 69.0 65.0 72.0 69.0 68.0 69.0 69.0 20:36:34 INFO - PROCESS | 2199 | -------- Summary: end -------- 20:36:34 INFO - PROCESS | 2199 | 20:36:35 INFO - TEST-INFO | 2199: exit 0 20:36:35 INFO - TEST-OK | kraken | took 61176ms 20:36:35 INFO - SUITE-END | took 1177s 20:36:35 INFO - Completed test suite (00:19:37) 20:36:35 INFO - javascript benchmark 20:36:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7091.766206339049, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3052.816830990142, 3132.592762940818, 3374.409478341291, 3429.1941393772477, 3462.982685086575, 4233.597808208219, 4425.0, 4627.8380256691025, 5010.874728131798, 5848.0, 5780.4219578042175, 5782.855428614287, 5842.766289348431, 5854.297484301879, 5873.324567674718, 4277.652539450079, 4406.206882761102, 4854.781534830923, 4996.300517927493, 5338.332708411449, 5225.954809038189, 5318.042752304584, 5344.732763361836, 5356.464353564632, 5376.193570964348, 4285.614294713475, 4337.587929146726, 5258.421573626898, 5322.148456247001, 5382.515573598375, 5211.348581427322, 5296.761645725952, 5318.521333080042, 5366.9463305366835, 5396.271503347057, 5149.253358263069, 5190.688558686491, 5226.451222621608, 5249.790008399659, 5251.52736253739, 4872.415310162768, 5395.0, 5726.627769194989, 5729.484346408802, 5814.273215848019, 6189.535784816157, 6242.0, 6252.468540174091, 6264.1543391642, 6293.118963345135, 6161.7227224775, 6216.937830621681, 6253.96873015632, 6261.530385221126, 6292.244930608356, 5710.172025056344, 5748.0, 5756.856078598027, 5792.855178620568, 5862.14998825168, 6226.844328891814, 6288.465480434214, 6289.40250676185, 6293.496520278367, 6322.841928951813, 5991.7902873399225, 6094.299155597163, 6106.175666284995, 6109.5112391008615, 6135.0, 6151.954167791486, 6166.383361663798, 6200.348963358872, 6237.313895471494, 6238.75044998195, 6202.906956395657, 6223.875522489525, 6272.717727702265, 6296.307406185316, 6297.716602752887, 6103.761967186988, 6138.1738283688655, 6146.3404880681965, 6201.658908760061, 6275.748970041147, 4116.897077573085, 4288.485381754147, 4975.477574854661, 4992.101421744048, 5047.97476012625, 4380.036391993756, 4397.494288156839, 4467.888302792456, 4468.0, 4508.954910450853, 4747.786349614275, 5965.492933100648, 6010.128531362928, 6018.00702884028, 6018.668973206516, 6160.045192995093, 6269.529785266123, 6327.746890124436, 6332.430081292706, 6341.270753863272, 5858.677772722541, 5872.911906321408, 5887.793927212527, 5897.705114744245, 5915.201447804491, 5481.397046324901, 5489.505944465017, 5493.368262649847, 5516.862078447991, 5527.834164975057, 5333.386660534088, 6430.260520040255, 6447.742090316428, 6457.2896981332015, 6468.773592924225, 6135.477655820289, 6149.754009839557, 6209.292444577777, 6249.96875015622, 6251.6874156292915, 5782.681952492653, 6007.768407476544, 6382.393672601095, 6473.943595430877, 6523.3150519194805, 6192.845178870564, 6206.472449841814, 6219.626822390671, 6232.719527621177, 6280.780172693934, 4937.975310123426, 5090.727318170458, 6234.127222188807, 6235.158253635701, 6280.842978925563, 6016.217891674054, 6125.601746185282, 6130.581587308013, 6164.8767024658355, 6165.445109940127, 5210.817621383233, 6174.66039367839, 6231.626102433868, 6249.750009999549, 6258.186435763321, 5089.541941225308, 5828.388019257917, 5933.495652869555, 5977.641341519523, 5983.880322393615, 6192.97815860378, 6225.719842607185, 6248.468880145057, 6264.154339164154, 6272.655003974825, 5928.170056192051, 5990.4908082813445, 6113.052476866093, 6145.985912324416, 6146.661933593696], "value": 5639.035787183868, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.08857064444516, 460.16250424227917, 460.6038806626301, 514.4212760644275, 530.9097453432918, 29116.854415727918, 38050.238995220076, 38058.0, 38110.04724881872, 38226.04434889129, 39694.603053969455, 40057.799711001404, 40196.79901600488, 40279.59720402795, 40282.59717402825, 7468.327850493454, 8595.742127736177, 9238.722838314845, 9439.669611563597, 9462.1484066434, 10931.45342732864, 11260.887391126065, 11294.435278236097, 11383.772324553503, 11431.657050288504, 11271.774564508705, 11294.0, 11356.602518911839, 11366.147538934572, 11384.772304553904, 11142.721431964144, 11194.720131996684, 11256.437178141101, 11297.491612877358, 11348.297755336765, 10967.067799237075, 11041.39272340021, 11064.28082174656, 11083.778324433466, 11154.944225278821, 38037.23925521502, 38218.80890595557, 38490.422643660364, 38497.0, 38503.422448663296, 3271.770976031679, 3323.7174840138377, 3375.679310465526, 3388.440907250301, 3449.0859922120408, 25176.49647007068, 29191.70808291911, 30156.54765178524, 30204.0938771835, 30850.691493085007, 3200.983995080033, 3227.1770698471973, 3235.9352812943607, 3241.092494101655, 3316.8507417166043, 383661.0, 484017.0, 489601.0, 493963.0, 495913.0, 63588.68205658942, 64507.677461612395, 64539.0, 64567.677161613894, 64685.70628587402, 66843.66578167175, 66872.33127668661, 67245.0, 67314.66342668256, 67458.0, 15526.611834703997, 15995.920020400057, 16009.599760006093, 16018.679626407407, 16080.597985050468, 10787.244892857581, 10954.561817527368, 12062.97464715509, 12158.209716368408, 12326.876731232753, 19385.70064655288, 19422.70865937012, 19492.610147797248, 19528.70706939397, 19555.217791288473, 7776.533407995538, 8213.89112469809, 8249.29880960125, 8415.158484151658, 8434.831303374021, 731.5062332925361, 961.8028304197622, 964.363520076746, 1028.6142696488816, 1051.7116532248087, 24704.752952470604, 28374.0, 28715.682549839003, 29338.0, 29362.412751744847, 874.9868751968726, 1003.6487229469773, 1005.1255407795119, 1034.8447732840134, 1062.0972173652333, 13702.0, 14608.707825843636, 14854.70290594182, 15062.472813451477, 15063.397464101314, 656349.0, 661709.0, 669400.0, 669514.0, 672061.0, 42892.78553607212, 52637.47362526402, 52739.472605274226, 53220.0, 53279.93440131176, 1151.6142092398973, 1339.216558313378, 1373.8145350377772, 1376.6558360409697, 1391.512970460351, 24060.87969560141, 28427.573586395807, 28471.572926406534, 28572.85713571419, 29388.412231754817, 45763.31355029611, 46297.07405851931, 46556.53443465522, 46631.0, 46727.29909051435, 552833.0, 553281.0, 557916.0, 557928.0, 558022.0, 32886.84896028723, 36709.0, 39073.804630976665, 39080.41379379368, 39526.60473395229, 40116.59883401245, 42885.785571071945, 42962.78518607387, 43545.0, 43722.34416483692, 36250.27499450049, 36314.273714525036, 36436.635633644386, 37102.628973709914, 37712.62287377091, 35784.0, 35836.64163358333, 36965.075873103386, 37025.0, 37063.629363706015, 34558.13604659904, 34940.650593494756, 35250.94247172589, 35381.0, 35775.64224357723, 27300.59049114225, 29530.852345738134, 32155.0, 32266.0, 32504.83747581247, 24393.02427902935, 24464.877675611508, 24478.75521244836, 24569.631455527822, 24736.752632474167], "value": 19846.419879135196, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.52526039326114, 369.52331492374833, 379.70193398182425, 382.6154714511915, 401.22563452536593, 18691.71962420563, 19337.903310483463, 19346.51633709156, 20085.59828803425, 20250.696239556382, 11332.660020199386, 11760.000399966013, 11769.117316201276, 11782.528698852035, 11803.173777835533, 5030.647854650176, 5319.840404787863, 5381.488758567949, 5430.15832545956, 5438.265834112403, 3879.5538513071074, 4090.836366545335, 4841.757912104398, 5311.1502159654465, 5356.1697936819855, 4096.5698601646845, 5847.1521629363715, 6501.284858665566, 6591.406773390394, 6670.366315200073, 5765.538756899438, 6038.849028774316, 6589.505787065966, 6629.138212032429, 6637.701303441357, 5701.173329867188, 5746.36789953109, 5758.337791154029, 5762.308522977228, 5779.450952159538, 12523.311217883012, 21371.893140534354, 24475.26574202777, 24511.38721531976, 24518.754812451825, 2147.4146977058836, 2164.0802658869916, 2253.6281513550407, 2261.0, 2271.954560908789, 9689.32174747768, 10207.234457415723, 10347.327423717434, 12353.135280530367, 14431.567052988426, 1766.6378392429522, 1817.872748907577, 1818.4908225696827, 1827.8080801515916, 1843.7050071988551, 69145.99999999949, 93942.0, 102066.46900296501, 112890.0, 119600.0, 8530.402871799039, 10121.493925303705, 11458.828117578243, 11506.0, 11553.768924621629, 11681.357525336018, 14413.49552765669, 14438.855611443962, 14474.565763027125, 14501.709965800836, 8221.095679475253, 8364.749057528285, 8400.285975692135, 8413.747587572383, 8564.657413703506, 6725.831854203586, 6797.184337879387, 8246.54643994586, 8248.030708944774, 8385.245327920395, 3326.7690954347045, 4368.475782906072, 4439.955600444019, 4467.642588592905, 4475.664325175625, 1520.635047588593, 1805.9705967598607, 1889.782674992366, 1930.835878950305, 1941.8737782044122, 802.5144787403653, 861.7587075618836, 894.8299823033733, 952.4142652268805, 978.280963491833, 18080.276788928295, 18098.0, 18849.34027309065, 21189.78810211909, 21249.0, 888.8311220868014, 931.65063101337, 954.7088138117801, 965.9275554333312, 967.7919247361851, 10685.038346548692, 11307.717307067225, 11452.828207576893, 11709.707257318636, 11736.354500502553, 506023.0, 506346.0, 507209.0, 532406.0, 551011.0, 76057.6197119022, 77624.83562746564, 78649.60675196587, 119178.0, 131562.0, 830.2569200565581, 845.8392905347964, 929.6327950459531, 954.513198268874, 989.6684610655367, 16679.332826686797, 18848.62302753965, 19209.423717288504, 19284.13221405068, 19314.903425482782, 36520.0, 41829.372559412244, 53911.382658520306, 54480.727596361765, 55807.72096139493, 50607.0, 53262.40212793622, 60870.69564652148, 76257.23742762502, 80762.0, 10763.300385474919, 11155.107591392507, 11322.94338528302, 11534.0, 11767.0, 11286.717832054264, 11378.31730096197, 12507.74984500323, 12794.488220471078, 12817.935910320388, 10801.621943232263, 11177.720556985814, 11210.0, 11212.719682008015, 11224.831627525431, 10701.678949631523, 10900.618478353219, 11027.83458248111, 11314.208005439541, 11325.377104259345, 9053.0, 9386.718398448058, 11255.831162532404, 11348.489317980875, 11504.137189710973, 9684.95157524208, 11214.607488737855, 11849.466773995364, 11893.643190704293, 11982.10134239918, 10783.568657253936, 10897.782044359226, 11287.604933827277, 11461.083113351078, 11462.197646164688], "value": 9129.631619246287, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1471.7277303698816, 1490.2325302469228, 1663.20997526175, 1762.8237176282366, 1790.6955817511014, 7538.359239464652, 7815.335696465807, 8076.838463230732, 8397.076321604618, 8464.873026904594, 7960.442769006172, 8291.419600627958, 8297.460665056768, 8344.457610255344, 8362.0, 2264.682944387787, 2283.771622837711, 2294.8967296471615, 2297.115610489965, 2298.5977453945575, 2295.5064661097863, 2325.3953971967326, 2337.2637619149914, 2340.8361414700976, 2341.016772955354, 2278.2481781012225, 2280.680704701343, 2303.1248125712204, 2303.550807592512, 2310.260716570698, 2260.7400148982915, 2267.671187677794, 2283.6232021716396, 2285.6800047993293, 2294.919677811277, 2240.6639004149506, 2285.3144056783067, 2299.379167624749, 2309.884505774721, 2309.9720624322185, 8279.089300176976, 8303.294219991309, 8359.874601880976, 8363.707270245512, 8393.076761556224, 5004.9499505005215, 5155.32980712505, 5156.535911767922, 5157.30376399185, 5185.61107916904, 3167.5407065975305, 3242.951355729666, 3469.5663042119736, 3473.5484387029524, 3473.548438702978, 988.3180605382263, 1023.5240613114937, 1028.4908970059794, 1028.8353863381824, 1036.8496567997674, 10667.759770448216, 10673.946630266877, 10674.306170098918, 10828.40443775592, 10831.67504974852, 3645.65366290202, 4571.748553829505, 4649.721016739007, 4668.95331046692, 4677.579017888338, 9143.222826059859, 9258.259339252843, 9272.907270927204, 9277.304202184863, 9279.118483744034, 4361.520232774392, 5106.131957567222, 5128.0, 5130.127878260705, 5137.614678899097, 4197.76912269822, 4340.52254252032, 4490.865274041784, 4680.485146633868, 4687.765611719468, 3660.066682995819, 3705.444183372516, 3755.6619904208205, 3805.2009078093356, 3823.4264860270628, 1248.4132457744845, 1257.076049103908, 1260.5021016698354, 1267.8668739782374, 1271.7392934448417, 789.5302295134501, 868.1708967935606, 892.7455675132557, 919.2324409118461, 933.7758937854866, 5454.809081682122, 5579.2746942897975, 5610.793679358876, 5652.236948011966, 5676.574256930747, 859.3297228162044, 877.9780505487414, 898.3397203055749, 988.9505524723879, 994.2046362909643, 3086.5987421635045, 3346.8453383582623, 3390.423627983249, 3604.3151801157696, 3709.2581483703366, 9192.586333615003, 9849.310548261554, 9885.25860560461, 9896.307258491837, 9908.851367229498, 9615.134637882624, 9825.656102036395, 9841.55712992917, 9848.606055757833, 9860.506974651382, 7522.511036782592, 7769.145394006655, 7782.844343113106, 7802.375809935192, 7806.531608103532, 5356.437574054824, 5397.0, 5414.539764120094, 5454.645448045864, 5486.890262194814, 9007.819843603222, 9028.954855225682, 9058.682946096855, 9128.315376346933, 9183.54082295896, 9192.816143677222, 9211.723648290563, 9254.722358329262, 9357.251419886257, 9367.812643747224, 6267.96866015667, 6459.321771213955, 6554.344565543598, 6633.2040155181685, 6765.42493888025, 7834.6866125354345, 7931.762047138595, 7951.284384405431, 7952.36381089523, 7976.162502937108, 6931.896021559782, 7053.400460960671, 7089.716411343695, 7111.964440177766, 7198.0, 6873.347032031949, 6929.44564434834, 6947.617881016593, 7003.404710599656, 7003.719851205895, 6579.671016449254, 6612.173478315211, 6668.966655166693, 6684.264730879502, 6689.364510371507, 4818.301346304836, 4861.708297502161, 4878.463369029333, 4885.340479035285, 4937.580305674058, 4847.587955023721, 4848.660593758403, 4851.733154676526, 4919.0899683558655, 4943.431505376784], "value": 4355.129129935726, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.7935805257294, 299.2190383100109, 302.85311623862435, 306.1656984716646, 309.31949710636604, 5106.055379754739, 5277.182036784295, 5307.416184219743, 5419.674819510828, 5481.643693159946, 4024.396340548919, 4932.078997197228, 5071.6053085401445, 5279.947200527993, 5304.655197412166, 3346.665333466652, 4066.654334381581, 4104.753714777108, 4111.732737372061, 4141.358089496133, 3494.4408894576873, 3907.960920390802, 4025.0, 4106.28140075487, 4144.730592511477, 4032.778197199153, 4061.8375264989363, 4066.9389959150635, 4081.795910204493, 4082.734622249559, 3163.9525407118913, 3456.8962931112105, 3558.822058897045, 3979.283728928792, 4092.2812418783883, 2804.088671181874, 2926.590277361171, 3060.8622611982514, 3088.583041289442, 3536.8231588420476, 4183.205191013728, 4656.813727450898, 5162.793488260465, 5201.245819356172, 5227.79088836446, 3842.7886466244345, 3858.536975562922, 4554.089182163548, 4569.246074397721, 4586.472555656109, 5178.17149256121, 5562.415946325618, 5630.380658127644, 5650.773969041234, 5652.858678533028, 3542.990247779396, 3564.536610240652, 3795.658390744846, 3850.306944749944, 3878.8448462061483, 5400.432954539756, 5417.241586177938, 5609.046462101453, 5795.507381872546, 5874.357898103161, 3392.49112633107, 3848.807559622008, 3866.4393662919283, 3879.2241551689776, 3880.4761357216416, 3922.7254092213843, 3925.391564307537, 3925.568187499373, 3930.331843586597, 3949.2299001694396, 3616.367135751233, 4446.6220371268155, 4568.33759104928, 4583.587477127075, 4607.0, 4773.928391074137, 5380.677159370451, 5634.943650563524, 5660.801871934545, 5661.971690141523, 1089.7929393415225, 1114.7603265298, 1202.705337192376, 1217.500824661902, 1276.5213045108085, 763.3778470546487, 873.5021038008274, 905.2667339455063, 908.68650315641, 929.8233335666201, 4319.408354987046, 4938.802447902043, 5018.2974383586325, 5068.265101560327, 5141.53726164647, 5153.510416510426, 5224.50367215114, 5375.7849686012905, 5547.362053363834, 5581.944180558224, 4040.5353384361174, 4407.625351845129, 4416.271315233015, 4998.050370429607, 5022.0620936751675, 2426.4419183587875, 2905.1575043237226, 2921.8246905185756, 2922.313256384748, 2925.9122226333243, 4230.830766769294, 5727.828165155052, 5729.598928074995, 5897.375667827775, 6146.508279337642, 5985.700714964321, 6091.299500557493, 6103.786367477206, 6104.542159338067, 6146.692665366714, 4721.929171062437, 5837.445442682938, 5976.308597750832, 6177.4749146323065, 6225.774225774226, 5367.570594352364, 5784.623999440023, 5815.738291776795, 5849.678267695318, 5930.733117009831, 5528.087865502147, 5769.730659254958, 5842.707864606838, 5843.590948633725, 5846.824595262149, 5753.597248192586, 5802.506786923159, 5842.597776533687, 5923.844850254159, 5948.627758597341, 3585.279066048259, 3882.22355528901, 3919.8040097994417, 3922.6861851052417, 3931.3709806430834, 4967.354243948408, 5184.948150518447, 5865.677387743715, 5903.409659034062, 5915.497182739343, 5369.6509726867625, 5499.9925027365525, 5598.580106491948, 5665.167416291854, 5777.422257774189, 4772.236442169236, 5656.773729050792, 5687.971560142173, 5705.315362156568, 5719.370869204301, 5200.531952124327, 5359.812406565751, 5451.618386712892, 5475.890482190254, 5617.74720137603, 5240.4759524047295, 5498.120300751861, 5669.829905102853, 5679.74441150141, 5693.943060569507, 5255.290535777621, 5563.8887222256135, 5596.216529685928, 5614.070150894472, 5618.887622247614], "value": 4044.5353324529374, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [636.6593872278332, 644.5005121031201, 645.4900628503483, 663.5521023309268, 740.4483659673544, 10539.946005399455, 11931.9261266486, 13487.865121348796, 13973.441062357506, 14740.115593064424, 11148.888511114887, 13389.129706569067, 13395.531156409528, 13402.93298533506, 13456.596302110927, 596.5555661032525, 820.2248874813304, 836.1471299274737, 836.8953880764905, 837.543538771368, 657.950653700972, 680.3774546290139, 815.4454970619976, 837.3217693668148, 845.8181490979438, 876.3996662286322, 885.8494056010494, 885.995570022149, 888.8622263549161, 889.181952603603, 871.6774793326479, 872.5898827551041, 874.6982291109557, 875.951822649754, 877.6182360673096, 652.879217344788, 653.0432915778407, 653.555582204101, 653.6208998780743, 654.8002859127964, 13648.181109133484, 14323.641908952255, 14348.354324055339, 14366.712665746627, 14367.78448323276, 12718.300493472856, 12944.417501212469, 13018.15382000167, 13195.8886578148, 13297.933510332387, 11184.217104802667, 11234.719132021683, 11294.322340659586, 11379.0, 11621.360825154614, 3633.1098880774116, 3633.7819730816236, 3638.890833275006, 3644.362236608583, 3769.830357633886, 93430.53284733601, 93448.0, 93815.0, 94384.05615943822, 94712.0, 10540.63107791239, 10542.736431589272, 10582.629607963685, 10649.893501065046, 10882.782344353227, 20471.4882127948, 20491.4877128073, 20492.540895092385, 20503.17987280492, 20520.973951302374, 7517.210692877169, 7584.5070070445245, 7615.5430674159725, 7649.286309413948, 7802.882956755653, 4237.131388065439, 4254.510731265882, 4322.891927701832, 4323.308270676676, 4360.149770794665, 3327.750418718606, 3373.983130084383, 3391.7286617070577, 3412.5904891412692, 3420.572428446444, 648.9188851393576, 661.1570247933827, 664.9734010639617, 674.8582797612556, 677.0994577212394, 566.6909439195389, 612.2469362683923, 618.5422787137576, 638.7604648256904, 639.6449970266547, 8636.0, 8929.642814287487, 8965.141666625037, 8981.416207946462, 9088.272938164931, 598.3999522076571, 627.9152314437583, 638.4158494977044, 664.6045602866287, 683.1801837794666, 4132.173565286955, 4223.894402639897, 4367.366731823868, 4417.337399390117, 4506.887327816765, 106986.46506767416, 107941.46029269803, 115398.0, 116338.0, 117013.24480132805, 101447.98552014533, 104633.47683261687, 104796.0, 105402.94597054085, 105676.47161764296, 87051.0, 87430.56284718536, 87591.0, 89057.0, 90563.54718226499, 8537.487750734976, 8576.44253123545, 8629.13708629132, 8655.95672021661, 8690.749426205171, 43618.34572481352, 52091.6977075561, 55716.0, 56160.0, 57384.12234960075, 69077.0, 76437.23562764301, 76999.0, 77444.0, 77755.44489110299, 10981.61564345244, 11050.33697978124, 11104.278221915549, 11105.0, 11395.886041139815, 11932.463039163085, 11961.521539138685, 11980.10149238828, 12014.75970480603, 12147.939260303641, 8783.429077109968, 11207.887921121011, 11405.885941140814, 11916.0, 11972.605478904079, 11354.943225283821, 11595.586516898373, 11789.823152652545, 11892.286462812259, 12116.818247726114, 9267.76830579241, 11746.119041071783, 11962.461689223832, 12084.577039803564, 12091.637250882488, 8668.266346730554, 9058.50178240203, 9222.170004699608, 9827.895409553259, 9926.652567160114, 7532.585707786125, 9059.954700226457, 9417.764555886157, 9980.900190997998, 10031.347962382422], "value": 7067.550847378005, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1577.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 119.0, 117.0, 117.0, 119.0, 118.0, 116.0, 117.0, 118.0, 118.0], "value": 116.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 103.0, 149.0, 189.0, 149.0, 115.0, 119.0, 116.0, 135.0, 115.0], "value": 133.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 198.0, 211.0, 231.0, 200.0, 198.0, 200.0, 196.0, 199.0, 181.0], "value": 200.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 60.0, 61.0, 85.0, 60.0, 62.0, 72.0, 51.0, 81.0, 83.0], "value": 70.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 83.0, 71.0, 70.0, 70.0, 80.0, 69.0, 72.0, 105.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 132.0, 89.0, 87.0, 90.0, 85.0, 89.0, 86.0, 90.0, 90.0], "value": 95.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 101.0, 100.0, 99.0, 109.0, 98.0, 145.0, 176.0, 99.0, 99.0], "value": 112.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 92.0, 91.0, 135.0, 93.0, 92.0, 92.0, 96.0, 93.0], "value": 97.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 56.0, 56.0, 64.0, 74.0, 86.0, 89.0, 87.0, 89.0], "value": 71.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 82.0, 85.0, 76.0, 71.0, 70.0, 72.0, 74.0, 72.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 91.0, 90.0, 87.0, 91.0, 91.0, 94.0, 109.0, 112.0], "value": 94.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 150.0, 151.0, 153.0, 153.0, 153.0, 179.0, 237.0, 199.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 178.0, 173.0, 205.0, 244.0, 192.0, 211.0, 177.0, 178.0, 173.0], "value": 190.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 86.0, 95.0, 69.0, 65.0, 72.0, 69.0, 68.0, 69.0, 69.0], "value": 73.4, "unit": "score"}]}]} 20:36:35 INFO - Return code: 0 20:36:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:36:35 ERROR - # TBPL SUCCESS # 20:36:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:36:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:36:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:36:35 INFO - 2016-11-24 20:36:35.976 screenresolution[2204:24788] starting screenresolution argv=screenresolution get 20:36:35 INFO - 2016-11-24 20:36:35.980 screenresolution[2204:24788] Display 0: 1600x1200x32@60 20:36:35 INFO - 2016-11-24 20:36:35.986 screenresolution[2205:24791] starting screenresolution argv=screenresolution list 20:36:35 INFO - Available Modes on Display 0 20:36:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:36:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:36:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:36:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:36:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:36:36 INFO - Intel Iris: 20:36:36 INFO - Chipset Model: Intel Iris 20:36:36 INFO - Type: GPU 20:36:36 INFO - Bus: Built-In 20:36:36 INFO - VRAM (Dynamic, Max): 1536 MB 20:36:36 INFO - Vendor: Intel (0x8086) 20:36:36 INFO - Device ID: 0x0a2e 20:36:36 INFO - Revision ID: 0x0009 20:36:36 INFO - Displays: 20:36:36 INFO - Display: 20:36:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:36:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:36:36 INFO - Display Serial Number: 1600x1200 60 20:36:36 INFO - Main Display: Yes 20:36:36 INFO - Mirror: Off 20:36:36 INFO - Online: Yes 20:36:36 INFO - Rotation: Supported 20:36:36 INFO - Return code: 0 20:36:36 INFO - Running post-action listener: _resource_record_post_action 20:36:36 INFO - [mozharness: 2016-11-25 04:36:36.223003Z] Finished run-tests step (success) 20:36:36 INFO - Running post-run listener: _resource_record_post_run 20:36:36 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 811008; Write bytes: 696772608; Read time: 8; Write time: 1998 20:36:36 INFO - TinderboxPrint: CPU usage
54.4% 20:36:36 INFO - TinderboxPrint: I/O read bytes / time
811,008 / 8 20:36:36 INFO - TinderboxPrint: I/O write bytes / time
696,772,608 / 1,998 20:36:36 INFO - TinderboxPrint: CPU idle
2,208.6 (45.6%) 20:36:36 INFO - TinderboxPrint: CPU system
979.9 (20.2%) 20:36:36 INFO - TinderboxPrint: CPU user
1,657.2 (34.2%) 20:36:36 INFO - TinderboxPrint: Swap in / out
383,594,496 / 0 20:36:36 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 378282496; Write bytes: 372537344; Read time: 28835; Write time: 742 20:36:36 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 770048; Write bytes: 309739520; Read time: 7; Write time: 1211 20:36:36 INFO - Running post-run listener: _upload_blobber_files 20:36:36 INFO - Blob upload gear active. 20:36:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:36:36 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 20:36:36 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:36:36 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:36:37 INFO - (blobuploader) - INFO - Open directory for files ... 20:36:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:36:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:36:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:36:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:36:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:36:38 INFO - (blobuploader) - INFO - Done attempting. 20:36:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:36:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:36:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:36:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:36:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:36:39 INFO - (blobuploader) - INFO - Done attempting. 20:36:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:36:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:36:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:36:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:36:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:36:41 INFO - (blobuploader) - INFO - Done attempting. 20:36:41 INFO - (blobuploader) - INFO - Iteration through files over. 20:36:41 INFO - Return code: 0 20:36:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:36:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:36:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f4dfca46e2c016a110cfa8363820eca901f4c1d992b3ff684dee5392526604df03ad5520ab8e01e01283ace5e00a70d8cf66084571e2d3e9adce7d4f7fbc21d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9ca8ff8b50897ff6470b5902453624b0104895519594a207797401a4e876d74cf6a9d8f7653bfc8974a8c2369893890f17b4394d7cd556258856780e1ab904e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/299a6de7993740e1a9ec1398a0a3df2d0552a572702a6c617690ce6503ce38a07072d9f3c9da6bf9f2255e71623460b83e38dbf454dd7883331bdc335c3ff3e3"} 20:36:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:36:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:36:41 INFO - Contents: 20:36:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f4dfca46e2c016a110cfa8363820eca901f4c1d992b3ff684dee5392526604df03ad5520ab8e01e01283ace5e00a70d8cf66084571e2d3e9adce7d4f7fbc21d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9ca8ff8b50897ff6470b5902453624b0104895519594a207797401a4e876d74cf6a9d8f7653bfc8974a8c2369893890f17b4394d7cd556258856780e1ab904e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/299a6de7993740e1a9ec1398a0a3df2d0552a572702a6c617690ce6503ce38a07072d9f3c9da6bf9f2255e71623460b83e38dbf454dd7883331bdc335c3ff3e3"} 20:36:41 INFO - Running post-run listener: copy_logs_to_upload_dir 20:36:41 INFO - Copying logs to upload dir... 20:36:41 INFO - mkdir: /builds/slave/test/build/upload/logs 20:36:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.635355 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-24 20:36:41.422602) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-24 20:36:41.426418) ========= 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.QAceOpEwPL/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.Tl0xDhPGKF/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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/Graphics/sha512/f4dfca46e2c016a110cfa8363820eca901f4c1d992b3ff684dee5392526604df03ad5520ab8e01e01283ace5e00a70d8cf66084571e2d3e9adce7d4f7fbc21d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9ca8ff8b50897ff6470b5902453624b0104895519594a207797401a4e876d74cf6a9d8f7653bfc8974a8c2369893890f17b4394d7cd556258856780e1ab904e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/299a6de7993740e1a9ec1398a0a3df2d0552a572702a6c617690ce6503ce38a07072d9f3c9da6bf9f2255e71623460b83e38dbf454dd7883331bdc335c3ff3e3"} build_url:https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010589 build_url: 'https://queue.taskcluster.net/v1/task/N1wmgnSLRKasavjxuhrFeQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f4dfca46e2c016a110cfa8363820eca901f4c1d992b3ff684dee5392526604df03ad5520ab8e01e01283ace5e00a70d8cf66084571e2d3e9adce7d4f7fbc21d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9ca8ff8b50897ff6470b5902453624b0104895519594a207797401a4e876d74cf6a9d8f7653bfc8974a8c2369893890f17b4394d7cd556258856780e1ab904e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/299a6de7993740e1a9ec1398a0a3df2d0552a572702a6c617690ce6503ce38a07072d9f3c9da6bf9f2255e71623460b83e38dbf454dd7883331bdc335c3ff3e3"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-24 20:36:41.465152) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 20:36:41.465570) ========= 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.QAceOpEwPL/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.Tl0xDhPGKF/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.005241 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 20:36:41.515039) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-24 20:36:41.515405) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-24 20:36:41.515749) ========= ========= Total master_lag: 0.36 =========