builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0344 starttime: 1477757842.33 results: success (0) buildid: 20161029063803 builduid: 4ab4933d5a474c8c9fb474ac4aa33324 revision: 969c3295d3aa77931cca26eddb047d9d74bd9858 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 09:17:22.326253) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 09:17:22.326688) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 09:17:22.326961) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 09:17:22.352865) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 09:17:22.353250) ========= 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.39bIGHaPDr/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.9OVJ0Xm7qS/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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-10-29 09:17:22-- 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% 18.6M=0.001s 2016-10-29 09:17:22 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.076179 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 09:17:22.452006) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 09:17:22.452318) ========= 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.39bIGHaPDr/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.9OVJ0Xm7qS/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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.023862 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 09:17:22.501445) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 09:17:22.501840) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 969c3295d3aa77931cca26eddb047d9d74bd9858 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 969c3295d3aa77931cca26eddb047d9d74bd9858 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.39bIGHaPDr/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.9OVJ0Xm7qS/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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-10-29 09:17:22,587 truncating revision to first 12 chars 2016-10-29 09:17:22,587 Setting DEBUG logging. 2016-10-29 09:17:22,587 attempt 1/10 2016-10-29 09:17:22,587 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/969c3295d3aa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-29 09:17:23,563 unpacking tar archive at: mozilla-central-969c3295d3aa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.235966 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 09:17:23.759992) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 09:17:23.760333) ========= script_repo_revision: 969c3295d3aa77931cca26eddb047d9d74bd9858 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 09:17:23.760739) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 09:17:23.761013) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 09:17:23.780736) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-29 09:17:23.781141) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.39bIGHaPDr/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.9OVJ0Xm7qS/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 09:17:23 INFO - MultiFileLogger online at 20161029 09:17:23 in /builds/slave/test 09:17:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:17:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:17:23 INFO - {'append_to_log': False, 09:17:23 INFO - 'base_work_dir': '/builds/slave/test', 09:17:23 INFO - 'blob_upload_branch': 'Firefox', 09:17:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:17:23 INFO - 'branch': 'Firefox', 09:17:23 INFO - 'buildbot_json_path': 'buildprops.json', 09:17:23 INFO - 'config_files': ('talos/mac_config.py',), 09:17:23 INFO - 'default_actions': ('clobber', 09:17:23 INFO - 'read-buildbot-config', 09:17:23 INFO - 'download-and-extract', 09:17:23 INFO - 'populate-webroot', 09:17:23 INFO - 'create-virtualenv', 09:17:23 INFO - 'install', 09:17:23 INFO - 'run-tests'), 09:17:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:17:23 INFO - 'download_minidump_stackwalk': True, 09:17:23 INFO - 'download_symbols': 'ondemand', 09:17:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:17:23 INFO - 'tooltool.py': '/tools/tooltool.py', 09:17:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:17:23 INFO - '/tools/misc-python/virtualenv.py')}, 09:17:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:17:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:17:23 INFO - 'installer_path': 'installer.exe', 09:17:23 INFO - 'log_level': 'info', 09:17:23 INFO - 'log_name': 'talos', 09:17:23 INFO - 'log_to_console': True, 09:17:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:17:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:17:23 INFO - 'opt_config_files': (), 09:17:23 INFO - 'pip_index': False, 09:17:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:17:23 INFO - 'cmd': ('bash', 09:17:23 INFO - '-c', 09:17:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:17:23 INFO - 'enabled': True, 09:17:23 INFO - 'halt_on_failure': False, 09:17:23 INFO - 'name': 'check_screen_resolution'},), 09:17:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:17:23 INFO - 'cmd': ('bash', 09:17:23 INFO - '-c', 09:17:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:17:23 INFO - 'enabled': True, 09:17:23 INFO - 'halt_on_failure': False, 09:17:23 INFO - 'name': 'check_screen_resolution'},), 09:17:23 INFO - 'run_cmd_checks_enabled': True, 09:17:23 INFO - 'sps_profile': False, 09:17:23 INFO - 'sps_profile_interval': 0, 09:17:23 INFO - 'suite': 'dromaeojs-e10s', 09:17:23 INFO - 'system_bits': '32', 09:17:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:17:23 INFO - 'title': 't-yosemite-r7-0344', 09:17:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:17:23 INFO - 'use_talos_json': True, 09:17:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:17:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:17:23 INFO - 'work_dir': 'build'} 09:17:23 INFO - [mozharness: 2016-10-29 16:17:23.988736Z] Running clobber step. 09:17:23 INFO - Running pre-action listener: _resource_record_pre_action 09:17:23 INFO - Running main action method: clobber 09:17:23 INFO - rmtree: /builds/slave/test/build 09:17:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:17:26 INFO - Running post-action listener: _resource_record_post_action 09:17:26 INFO - [mozharness: 2016-10-29 16:17:26.032003Z] Finished clobber step (success) 09:17:26 INFO - [mozharness: 2016-10-29 16:17:26.032134Z] Running read-buildbot-config step. 09:17:26 INFO - Running pre-action listener: _resource_record_pre_action 09:17:26 INFO - Running main action method: read_buildbot_config 09:17:26 INFO - Using buildbot properties: 09:17:26 INFO - { 09:17:26 INFO - "project": "", 09:17:26 INFO - "product": "firefox", 09:17:26 INFO - "script_repo_revision": "production", 09:17:26 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:17:26 INFO - "repository": "", 09:17:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:17:26 INFO - "buildid": "20161029063803", 09:17:26 INFO - "pgo_build": "False", 09:17:26 INFO - "basedir": "/builds/slave/test", 09:17:26 INFO - "buildnumber": 54, 09:17:26 INFO - "slavename": "t-yosemite-r7-0344", 09:17:26 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:17:26 INFO - "platform": "yosemite_r7", 09:17:26 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:17:26 INFO - "branch": "mozilla-central", 09:17:26 INFO - "repo_path": "mozilla-central", 09:17:26 INFO - "revision": "969c3295d3aa77931cca26eddb047d9d74bd9858", 09:17:26 INFO - "stage_platform": "macosx64", 09:17:26 INFO - "builduid": "4ab4933d5a474c8c9fb474ac4aa33324", 09:17:26 INFO - "slavebuilddir": "test" 09:17:26 INFO - } 09:17:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 09:17:26 INFO - Running post-action listener: _resource_record_post_action 09:17:26 INFO - [mozharness: 2016-10-29 16:17:26.035437Z] Finished read-buildbot-config step (success) 09:17:26 INFO - [mozharness: 2016-10-29 16:17:26.035567Z] Running download-and-extract step. 09:17:26 INFO - Running pre-action listener: _resource_record_pre_action 09:17:26 INFO - Running main action method: download_and_extract 09:17:26 INFO - mkdir: /builds/slave/test/build/tests 09:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:26 INFO - trying https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 09:17:26 INFO - Downloading https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 09:17:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 09:17:27 INFO - Downloaded 1199 bytes. 09:17:27 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 09:17:27 INFO - Using the following test package requirements: 09:17:27 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:17:27 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:17:27 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 09:17:27 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:17:27 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 09:17:27 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:17:27 INFO - u'jsshell-mac.zip'], 09:17:27 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:17:27 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 09:17:27 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:17:27 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:17:27 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 09:17:27 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:17:27 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 09:17:27 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:17:27 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 09:17:27 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:17:27 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 09:17:27 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:17:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:17:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:17:27 INFO - Fetch https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:17:27 INFO - Http code: 200 09:17:27 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 09:17:27 INFO - x-amz-cf-id: d1MsFyuP77WOGKPKtAMLKiKQVsR-SEAv5aQsQ37dlkU4wK_3pFfiKg== 09:17:27 INFO - x-amz-version-id: 2lMPW5ch_qbv0e6gF5QE4hEUdBlKX5DD 09:17:27 INFO - Expected file size: 16391696 09:17:27 INFO - Obtained file size: 16391696 09:17:34 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:17:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:17:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:17:34 INFO - Fetch https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:17:35 INFO - Http code: 200 09:17:35 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 09:17:35 INFO - x-amz-cf-id: 2VSGsN24TPh1MJVs_Xsh6lIKUepkavjJAg09dM152DtHkl8hx5EkvA== 09:17:35 INFO - x-amz-version-id: 2lMPW5ch_qbv0e6gF5QE4hEUdBlKX5DD 09:17:35 INFO - Expected file size: 16391696 09:17:35 INFO - Obtained file size: 16391696 09:17:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 09:17:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:17:41 INFO - Fetch https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 09:17:42 INFO - Http code: 200 09:17:42 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 09:17:42 INFO - x-amz-cf-id: YQfO3ARhW7TJHKsE9juEIxy3LtpnSh7AF0gN9VLZFCII08GR8Swfmg== 09:17:42 INFO - x-amz-version-id: k0meVR8cQV0i73WbyAYq6hsCxrKTmseM 09:17:43 INFO - Expected file size: 14409038 09:17:43 INFO - Obtained file size: 14409038 09:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:43 INFO - trying https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:17:43 INFO - Downloading https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 09:17:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 09:17:46 INFO - Downloaded 112035721 bytes. 09:17:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:17:46 INFO - mkdir: /builds/slave/test/properties 09:17:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:17:46 INFO - Writing to file /builds/slave/test/properties/build_url 09:17:46 INFO - Contents: 09:17:46 INFO - build_url:https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:17:47 INFO - Running post-action listener: _resource_record_post_action 09:17:47 INFO - Running post-action listener: set_extra_try_arguments 09:17:47 INFO - [mozharness: 2016-10-29 16:17:47.067015Z] Finished download-and-extract step (success) 09:17:47 INFO - [mozharness: 2016-10-29 16:17:47.067145Z] Running populate-webroot step. 09:17:47 INFO - Running pre-action listener: _resource_record_pre_action 09:17:47 INFO - Running main action method: populate_webroot 09:17:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:17:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:17:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:17:47 INFO - '--tppagecycles', 09:17:47 INFO - '7'], 09:17:47 INFO - 'tests': ['tsvgm']}}, 09:17:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:17:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:17:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:17:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:17:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:17:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:17:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:47 INFO - 'talos_options': ['--disable-e10s'], 09:17:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:47 INFO - 'talos_options': ['--disable-e10s'], 09:17:47 INFO - 'tests': ['damp', 'tps']}, 09:17:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:47 INFO - 'tests': ['damp', 'tps']}, 09:17:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:17:47 INFO - 'tests': ['dromaeo_dom']}, 09:17:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:17:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:17:47 INFO - 'tests': ['basic_compositor_video']}, 09:17:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:17:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:17:47 INFO - 'tests': ['a11yr', 09:17:47 INFO - 'ts_paint', 09:17:47 INFO - 'tpaint', 09:17:47 INFO - 'sessionrestore', 09:17:47 INFO - 'sessionrestore_no_auto_restore', 09:17:47 INFO - 'tabpaint']}, 09:17:47 INFO - 'other-e10s': {'tests': ['a11yr', 09:17:47 INFO - 'ts_paint', 09:17:47 INFO - 'tpaint', 09:17:47 INFO - 'sessionrestore', 09:17:47 INFO - 'sessionrestore_no_auto_restore', 09:17:47 INFO - 'tabpaint']}, 09:17:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:17:47 INFO - 'ts_paint', 09:17:47 INFO - 'tpaint', 09:17:47 INFO - 'sessionrestore', 09:17:47 INFO - 'sessionrestore_no_auto_restore', 09:17:47 INFO - 'tabpaint']}, 09:17:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:17:47 INFO - 'ts_paint', 09:17:47 INFO - 'tpaint', 09:17:47 INFO - 'sessionrestore', 09:17:47 INFO - 'sessionrestore_no_auto_restore', 09:17:47 INFO - 'tabpaint']}, 09:17:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:17:47 INFO - 'tests': ['a11yr', 09:17:47 INFO - 'ts_paint', 09:17:47 INFO - 'tpaint', 09:17:47 INFO - 'sessionrestore', 09:17:47 INFO - 'sessionrestore_no_auto_restore', 09:17:47 INFO - 'tabpaint']}, 09:17:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:17:47 INFO - 'tests': ['a11yr', 09:17:47 INFO - 'ts_paint', 09:17:47 INFO - 'tpaint', 09:17:47 INFO - 'sessionrestore', 09:17:47 INFO - 'sessionrestore_no_auto_restore', 09:17:47 INFO - 'tabpaint']}, 09:17:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:17:47 INFO - 'tests': ['tsvgx', 09:17:47 INFO - 'tsvgr_opacity', 09:17:47 INFO - 'tart', 09:17:47 INFO - 'tscrollx', 09:17:47 INFO - 'cart']}, 09:17:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:17:47 INFO - 'tsvgr_opacity', 09:17:47 INFO - 'tart', 09:17:47 INFO - 'tscrollx', 09:17:47 INFO - 'cart']}, 09:17:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:47 INFO - 'talos_options': ['--disable-e10s'], 09:17:47 INFO - 'tests': ['tp5o']}, 09:17:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:47 INFO - 'tests': ['tp5o']}, 09:17:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:47 INFO - 'talos_options': ['--disable-e10s', 09:17:47 INFO - '--xperf_path', 09:17:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:47 INFO - 'tests': ['tp5n']}, 09:17:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:47 INFO - 'talos_options': ['--xperf_path', 09:17:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:47 INFO - 'tests': ['tp5n']}}, 09:17:47 INFO - 'talos.zip': {'path': '', 09:17:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:17:47 INFO - Running post-action listener: _resource_record_post_action 09:17:47 INFO - [mozharness: 2016-10-29 16:17:47.085725Z] Finished populate-webroot step (success) 09:17:47 INFO - [mozharness: 2016-10-29 16:17:47.085855Z] Running create-virtualenv step. 09:17:47 INFO - Running pre-action listener: _resource_record_pre_action 09:17:47 INFO - Running main action method: create_virtualenv 09:17:47 INFO - Creating virtualenv /builds/slave/test/build/venv 09:17:47 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 09:17:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:17:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:17:47 INFO - Using real prefix '/tools/python27' 09:17:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:17:47 INFO - Installing distribute.............................................................................................................................................................................................done. 09:17:50 INFO - Installing pip.................done. 09:17:50 INFO - Return code: 0 09:17:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:17:51 INFO - Reading from file tmpfile_stdout 09:17:51 INFO - Output received: 09:17:51 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) 09:17:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e364490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e178d30>, '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 0x10df9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e170b20>, '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.39bIGHaPDr/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.9OVJ0Xm7qS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 09:17:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:17:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:17:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39bIGHaPDr/Render', 09:17:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:51 INFO - 'HOME': '/Users/cltbld', 09:17:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:51 INFO - 'LOGNAME': 'cltbld', 09:17:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:51 INFO - 'MOZ_NO_REMOTE': '1', 09:17:51 INFO - 'NO_EM_RESTART': '1', 09:17:51 INFO - 'PAGER': '/bin/cat', 09:17:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:51 INFO - 'PWD': '/builds/slave/test', 09:17:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:51 INFO - 'SHELL': '/bin/bash', 09:17:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9OVJ0Xm7qS/Listeners', 09:17:51 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 09:17:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:51 INFO - 'USER': 'cltbld', 09:17:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:51 INFO - 'XPC_FLAGS': '0x0', 09:17:51 INFO - 'XPC_SERVICE_NAME': '0', 09:17:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:51 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 09:17:51 INFO - Cleaning up... 09:17:51 INFO - Return code: 0 09:17:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e364490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e178d30>, '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 0x10df9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e170b20>, '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.39bIGHaPDr/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.9OVJ0Xm7qS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 09:17:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:17:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:17:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39bIGHaPDr/Render', 09:17:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:51 INFO - 'HOME': '/Users/cltbld', 09:17:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:51 INFO - 'LOGNAME': 'cltbld', 09:17:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:51 INFO - 'MOZ_NO_REMOTE': '1', 09:17:51 INFO - 'NO_EM_RESTART': '1', 09:17:51 INFO - 'PAGER': '/bin/cat', 09:17:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:51 INFO - 'PWD': '/builds/slave/test', 09:17:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:51 INFO - 'SHELL': '/bin/bash', 09:17:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9OVJ0Xm7qS/Listeners', 09:17:51 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 09:17:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:51 INFO - 'USER': 'cltbld', 09:17:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:51 INFO - 'XPC_FLAGS': '0x0', 09:17:51 INFO - 'XPC_SERVICE_NAME': '0', 09:17:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:51 INFO - Downloading/unpacking psutil>=3.1.1 09:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:17:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:55 INFO - Installing collected packages: psutil 09:17:55 INFO - Running setup.py install for psutil 09:17:55 INFO - building 'psutil._psutil_osx' extension 09:17:55 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 09:17:55 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 09:17:55 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 09:17:55 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 09:17:55 INFO - building 'psutil._psutil_posix' extension 09:17:55 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 09:17:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:17:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:17:55 INFO - ^ 09:17:55 INFO - 1 warning generated. 09:17:55 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 09:17:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:56 INFO - Successfully installed psutil 09:17:56 INFO - Cleaning up... 09:17:56 INFO - Return code: 0 09:17:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:17: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')]} 09:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17: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')]} 09:17:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e364490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e178d30>, '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 0x10df9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e170b20>, '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.39bIGHaPDr/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.9OVJ0Xm7qS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 09:17: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:17: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 mozsystemmonitor==0.3 09:17:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39bIGHaPDr/Render', 09:17:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:56 INFO - 'HOME': '/Users/cltbld', 09:17:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:56 INFO - 'LOGNAME': 'cltbld', 09:17:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:56 INFO - 'MOZ_NO_REMOTE': '1', 09:17:56 INFO - 'NO_EM_RESTART': '1', 09:17:56 INFO - 'PAGER': '/bin/cat', 09:17:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:56 INFO - 'PWD': '/builds/slave/test', 09:17:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:56 INFO - 'SHELL': '/bin/bash', 09:17:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9OVJ0Xm7qS/Listeners', 09:17:56 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 09:17:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:56 INFO - 'USER': 'cltbld', 09:17:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:56 INFO - 'XPC_FLAGS': '0x0', 09:17:56 INFO - 'XPC_SERVICE_NAME': '0', 09:17:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:17:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:17:59 INFO - Installing collected packages: mozsystemmonitor 09:17:59 INFO - Running setup.py install for mozsystemmonitor 09:17:59 INFO - Successfully installed mozsystemmonitor 09:17:59 INFO - Cleaning up... 09:17:59 INFO - Return code: 0 09:17:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:17:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:59 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 0x10e364490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e178d30>, '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 0x10df9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e170b20>, '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.39bIGHaPDr/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.9OVJ0Xm7qS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 09:17:59 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 09:17:59 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 09:17:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39bIGHaPDr/Render', 09:17:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:59 INFO - 'HOME': '/Users/cltbld', 09:17:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:59 INFO - 'LOGNAME': 'cltbld', 09:17:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:59 INFO - 'MOZ_NO_REMOTE': '1', 09:17:59 INFO - 'NO_EM_RESTART': '1', 09:17:59 INFO - 'PAGER': '/bin/cat', 09:17:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:59 INFO - 'PWD': '/builds/slave/test', 09:17:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:59 INFO - 'SHELL': '/bin/bash', 09:17:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9OVJ0Xm7qS/Listeners', 09:17:59 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 09:17:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:59 INFO - 'USER': 'cltbld', 09:17:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:59 INFO - 'XPC_FLAGS': '0x0', 09:17:59 INFO - 'XPC_SERVICE_NAME': '0', 09:17:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:59 INFO - Downloading/unpacking jsonschema==2.5.1 09:17:59 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 09:17:59 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 09:17:59 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 09:17:59 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 09:17:59 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 09:17:59 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 09:18:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:18:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18:02 INFO - Downloading functools32-3.2.3-2.tar.gz 09:18:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:18:02 INFO - warning: no files found matching '*.txt' 09:18:02 INFO - no previously-included directories found matching 'build' 09:18:02 INFO - no previously-included directories found matching 'dist' 09:18:02 INFO - no previously-included directories found matching '.git*' 09:18:02 INFO - Installing collected packages: jsonschema, functools32 09:18:02 INFO - Running setup.py install for functools32 09:18:02 INFO - warning: no files found matching '*.txt' 09:18:02 INFO - no previously-included directories found matching 'build' 09:18:02 INFO - no previously-included directories found matching 'dist' 09:18:02 INFO - no previously-included directories found matching '.git*' 09:18:02 INFO - Successfully installed jsonschema functools32 09:18:02 INFO - Cleaning up... 09:18:02 INFO - Return code: 0 09:18:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:02 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 0x10e364490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e178d30>, '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 0x10df9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e170b20>, '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.39bIGHaPDr/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.9OVJ0Xm7qS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 09:18:02 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 09:18:02 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 09:18:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39bIGHaPDr/Render', 09:18:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:02 INFO - 'HOME': '/Users/cltbld', 09:18:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:02 INFO - 'LOGNAME': 'cltbld', 09:18:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:02 INFO - 'MOZ_NO_REMOTE': '1', 09:18:02 INFO - 'NO_EM_RESTART': '1', 09:18:02 INFO - 'PAGER': '/bin/cat', 09:18:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:02 INFO - 'PWD': '/builds/slave/test', 09:18:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:02 INFO - 'SHELL': '/bin/bash', 09:18:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9OVJ0Xm7qS/Listeners', 09:18:02 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 09:18:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:02 INFO - 'USER': 'cltbld', 09:18:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:02 INFO - 'XPC_FLAGS': '0x0', 09:18:02 INFO - 'XPC_SERVICE_NAME': '0', 09:18:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:18:03 INFO - Cleaning up... 09:18:03 INFO - Return code: 0 09:18:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:03 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 0x10e364490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e178d30>, '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 0x10df9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e170b20>, '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.39bIGHaPDr/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.9OVJ0Xm7qS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 09:18:03 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 09:18:03 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 09:18:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39bIGHaPDr/Render', 09:18:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:03 INFO - 'HOME': '/Users/cltbld', 09:18:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:03 INFO - 'LOGNAME': 'cltbld', 09:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:03 INFO - 'MOZ_NO_REMOTE': '1', 09:18:03 INFO - 'NO_EM_RESTART': '1', 09:18:03 INFO - 'PAGER': '/bin/cat', 09:18:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:03 INFO - 'PWD': '/builds/slave/test', 09:18:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:03 INFO - 'SHELL': '/bin/bash', 09:18:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9OVJ0Xm7qS/Listeners', 09:18:03 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 09:18:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:03 INFO - 'USER': 'cltbld', 09:18:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:03 INFO - 'XPC_FLAGS': '0x0', 09:18:03 INFO - 'XPC_SERVICE_NAME': '0', 09:18:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:03 INFO - Downloading/unpacking blobuploader==1.2.4 09:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:05 INFO - Downloading blobuploader-1.2.4.tar.gz 09:18:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:18:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:18:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:07 INFO - Downloading docopt-0.6.1.tar.gz 09:18:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:18:07 INFO - Installing collected packages: blobuploader, requests, docopt 09:18:07 INFO - Running setup.py install for blobuploader 09:18:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:18:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:18:07 INFO - Running setup.py install for requests 09:18:07 INFO - Running setup.py install for docopt 09:18:07 INFO - Successfully installed blobuploader requests docopt 09:18:07 INFO - Cleaning up... 09:18:08 INFO - Return code: 0 09:18:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18: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')]} 09:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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')]} 09:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:08 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 0x10e364490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e178d30>, '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 0x10df9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e170b20>, '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.39bIGHaPDr/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.9OVJ0Xm7qS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 09:18:08 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 09:18:08 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 09:18:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39bIGHaPDr/Render', 09:18:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:08 INFO - 'HOME': '/Users/cltbld', 09:18:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:08 INFO - 'LOGNAME': 'cltbld', 09:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:08 INFO - 'MOZ_NO_REMOTE': '1', 09:18:08 INFO - 'NO_EM_RESTART': '1', 09:18:08 INFO - 'PAGER': '/bin/cat', 09:18:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:08 INFO - 'PWD': '/builds/slave/test', 09:18:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:08 INFO - 'SHELL': '/bin/bash', 09:18:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9OVJ0Xm7qS/Listeners', 09:18:08 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 09:18:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:08 INFO - 'USER': 'cltbld', 09:18:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:08 INFO - 'XPC_FLAGS': '0x0', 09:18:08 INFO - 'XPC_SERVICE_NAME': '0', 09:18:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:18:08 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-RZP86h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:18:08 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-L1UT2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:18:08 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-Y6x76U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:18:08 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-5YHIA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:18:08 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-Nf9NaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:18:08 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-qIkgVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:18:08 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-ZhXqTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:18:09 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-ng1aAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:18:09 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-ymbJU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:18:09 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-OChNNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:18:09 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-VjUS04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:18:09 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-TyNPT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:18:09 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-GLiaEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:18:09 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-cqhnHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:09 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-E6kk_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:18:09 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-j4ZeHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:18:10 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-XVmWih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:18:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:18:10 INFO - Running setup.py install for manifestparser 09:18:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:18:10 INFO - Running setup.py install for mozcrash 09:18:10 INFO - Running setup.py install for mozdebug 09:18:10 INFO - Running setup.py install for mozdevice 09:18:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:18:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:18:10 INFO - Running setup.py install for mozfile 09:18:10 INFO - Running setup.py install for mozhttpd 09:18:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:18:11 INFO - Running setup.py install for mozinfo 09:18:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:18:11 INFO - Running setup.py install for mozInstall 09:18:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:18:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:18:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:18:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:18:11 INFO - Running setup.py install for mozleak 09:18:11 INFO - Running setup.py install for mozlog 09:18:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:18:11 INFO - Running setup.py install for moznetwork 09:18:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:18:11 INFO - Running setup.py install for mozprocess 09:18:11 INFO - Running setup.py install for mozprofile 09:18:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:18:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:18:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:18:12 INFO - Running setup.py install for mozrunner 09:18:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:18:12 INFO - Running setup.py install for mozscreenshot 09:18:12 INFO - Running setup.py install for moztest 09:18:12 INFO - Running setup.py install for mozversion 09:18:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:18:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:18:12 INFO - Cleaning up... 09:18:12 INFO - Return code: 0 09:18:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:12 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 0x10e364490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e178d30>, '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 0x10df9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e170b20>, '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.39bIGHaPDr/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.9OVJ0Xm7qS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 09:18:12 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 09:18:12 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 09:18:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39bIGHaPDr/Render', 09:18:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:12 INFO - 'HOME': '/Users/cltbld', 09:18:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:12 INFO - 'LOGNAME': 'cltbld', 09:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:12 INFO - 'MOZ_NO_REMOTE': '1', 09:18:12 INFO - 'NO_EM_RESTART': '1', 09:18:12 INFO - 'PAGER': '/bin/cat', 09:18:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:12 INFO - 'PWD': '/builds/slave/test', 09:18:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:12 INFO - 'SHELL': '/bin/bash', 09:18:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9OVJ0Xm7qS/Listeners', 09:18:12 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 09:18:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:12 INFO - 'USER': 'cltbld', 09:18:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:12 INFO - 'XPC_FLAGS': '0x0', 09:18:12 INFO - 'XPC_SERVICE_NAME': '0', 09:18:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:18:12 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-hDIbcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:18:13 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)) 09:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:18:13 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-KBuSfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 09:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:18:13 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-qlhFag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:18:13 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)) 09:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:18:13 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-rdm1yE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 09:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:18:13 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-3kfuy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:18:13 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)) 09:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:18:13 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-SxO2ts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:18:13 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)) 09:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:18:13 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-Wj9iBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:18:13 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)) 09:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:18:13 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-CpFr5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:18:13 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)) 09:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:18:13 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-eNHGjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:18:13 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)) 09:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:18:13 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-8I2EpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:18:14 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)) 09:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:18:14 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-4soNQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:18:14 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)) 09:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:18:14 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-VmENcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:18:14 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)) 09:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:18:14 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-Icrjxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:18:14 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)) 09:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:18:14 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-_O4D67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 09:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:14 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-o0su1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:14 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)) 09:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:18:14 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-IP5BWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:18:14 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)) 09:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:18:14 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-qnYeW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:18:14 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)) 09:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:18:14 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)) 09:18:14 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:18:14 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:18:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:18:14 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 09:18:14 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 09:18:14 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 09:18:14 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 09:18:14 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 09:18:14 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 09:18:17 INFO - Downloading blessings-1.6.tar.gz 09:18:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:18:17 INFO - Installing collected packages: blessings 09:18:17 INFO - Running setup.py install for blessings 09:18:17 INFO - Successfully installed blessings 09:18:17 INFO - Cleaning up... 09:18:17 INFO - Return code: 0 09:18:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:18:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:18:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:18:17 INFO - Reading from file tmpfile_stdout 09:18:17 INFO - Current package versions: 09:18:17 INFO - blessings == 1.6 09:18:17 INFO - blobuploader == 1.2.4 09:18:17 INFO - docopt == 0.6.1 09:18:17 INFO - functools32 == 3.2.3-2 09:18:17 INFO - jsonschema == 2.5.1 09:18:17 INFO - manifestparser == 1.1 09:18:17 INFO - mozInstall == 1.12 09:18:17 INFO - mozcrash == 0.17 09:18:17 INFO - mozdebug == 0.1 09:18:17 INFO - mozdevice == 0.48 09:18:17 INFO - mozfile == 1.2 09:18:17 INFO - mozhttpd == 0.7 09:18:17 INFO - mozinfo == 0.9 09:18:17 INFO - mozleak == 0.1 09:18:17 INFO - mozlog == 3.4 09:18:17 INFO - moznetwork == 0.27 09:18:17 INFO - mozprocess == 0.23 09:18:17 INFO - mozprofile == 0.28 09:18:17 INFO - mozrunner == 6.12 09:18:17 INFO - mozscreenshot == 0.1 09:18:17 INFO - mozsystemmonitor == 0.3 09:18:17 INFO - moztest == 0.7 09:18:17 INFO - mozversion == 1.4 09:18:17 INFO - psutil == 3.1.1 09:18:17 INFO - requests == 1.2.3 09:18:17 INFO - wsgiref == 0.1.2 09:18:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:17 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 0x10e364490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e178d30>, '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 0x10df9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e170b20>, '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.39bIGHaPDr/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.9OVJ0Xm7qS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 09:18:17 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 09:18:17 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 09:18:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39bIGHaPDr/Render', 09:18:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:17 INFO - 'HOME': '/Users/cltbld', 09:18:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:17 INFO - 'LOGNAME': 'cltbld', 09:18:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:17 INFO - 'MOZ_NO_REMOTE': '1', 09:18:17 INFO - 'NO_EM_RESTART': '1', 09:18:17 INFO - 'PAGER': '/bin/cat', 09:18:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:17 INFO - 'PWD': '/builds/slave/test', 09:18:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:17 INFO - 'SHELL': '/bin/bash', 09:18:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9OVJ0Xm7qS/Listeners', 09:18:17 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 09:18:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:17 INFO - 'USER': 'cltbld', 09:18:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:17 INFO - 'XPC_FLAGS': '0x0', 09:18:17 INFO - 'XPC_SERVICE_NAME': '0', 09:18:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:18 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)) 09:18:18 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)) 09:18:18 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)) 09:18:18 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)) 09:18:18 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)) 09:18:18 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)) 09:18:18 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)) 09:18:18 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)) 09:18:18 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)) 09:18:18 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)) 09:18:18 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)) 09:18:18 INFO - Cleaning up... 09:18:18 INFO - Return code: 0 09:18:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:18: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')]} 09:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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')]} 09:18:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:18 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 0x10e364490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd6dcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e178d30>, '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 0x10df9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e170b20>, '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.39bIGHaPDr/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.9OVJ0Xm7qS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 09:18:18 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 09:18:18 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 09:18:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39bIGHaPDr/Render', 09:18:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:18 INFO - 'HOME': '/Users/cltbld', 09:18:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:18 INFO - 'LOGNAME': 'cltbld', 09:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:18 INFO - 'MOZ_NO_REMOTE': '1', 09:18:18 INFO - 'NO_EM_RESTART': '1', 09:18:18 INFO - 'PAGER': '/bin/cat', 09:18:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:18 INFO - 'PWD': '/builds/slave/test', 09:18:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:18 INFO - 'SHELL': '/bin/bash', 09:18:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9OVJ0Xm7qS/Listeners', 09:18:18 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 09:18:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:18 INFO - 'USER': 'cltbld', 09:18:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:18 INFO - 'XPC_FLAGS': '0x0', 09:18:18 INFO - 'XPC_SERVICE_NAME': '0', 09:18:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:18:18 INFO - Cleaning up... 09:18:18 INFO - Return code: 0 09:18:18 INFO - Running post-action listener: _resource_record_post_action 09:18:18 INFO - Running post-action listener: _start_resource_monitoring 09:18:18 INFO - Starting resource monitoring. 09:18:18 INFO - [mozharness: 2016-10-29 16:18:18.552676Z] Finished create-virtualenv step (success) 09:18:18 INFO - [mozharness: 2016-10-29 16:18:18.553660Z] Running install step. 09:18:18 INFO - Running pre-action listener: _resource_record_pre_action 09:18:18 INFO - Running main action method: install 09:18:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:18:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:18:18 INFO - Reading from file tmpfile_stdout 09:18:18 INFO - Detecting whether we're running mozinstall >=1.0... 09:18:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:18:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:18:18 INFO - Reading from file tmpfile_stdout 09:18:18 INFO - Output received: 09:18:18 INFO - Usage: mozinstall [options] installer 09:18:18 INFO - Options: 09:18:18 INFO - -h, --help show this help message and exit 09:18:18 INFO - -d DEST, --destination=DEST 09:18:18 INFO - Directory to install application into. [default: 09:18:18 INFO - "/builds/slave/test"] 09:18:18 INFO - --app=APP Application being installed. [default: firefox] 09:18:18 INFO - mkdir: /builds/slave/test/build/application 09:18:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:18:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:18:53 INFO - Reading from file tmpfile_stdout 09:18:53 INFO - Output received: 09:18:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:18:53 INFO - Running post-action listener: _resource_record_post_action 09:18:53 INFO - [mozharness: 2016-10-29 16:18:53.649693Z] Finished install step (success) 09:18:53 INFO - [mozharness: 2016-10-29 16:18:53.649833Z] Running run-tests step. 09:18:53 INFO - Running pre-action listener: _resource_record_pre_action 09:18:53 INFO - Running main action method: run_tests 09:18:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:18:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:18:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:18:53 INFO - 2016-10-29 09:18:53.664 screenresolution[1642:7541] starting screenresolution argv=screenresolution get 09:18:53 INFO - 2016-10-29 09:18:53.666 screenresolution[1642:7541] Display 0: 1600x1200x32@60 09:18:53 INFO - 2016-10-29 09:18:53.672 screenresolution[1643:7543] starting screenresolution argv=screenresolution list 09:18:53 INFO - Available Modes on Display 0 09:18:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:18:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:18:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:18:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:18:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:18:53 INFO - Intel Iris: 09:18:53 INFO - Chipset Model: Intel Iris 09:18:53 INFO - Type: GPU 09:18:53 INFO - Bus: Built-In 09:18:53 INFO - VRAM (Dynamic, Max): 1536 MB 09:18:53 INFO - Vendor: Intel (0x8086) 09:18:53 INFO - Device ID: 0x0a2e 09:18:53 INFO - Revision ID: 0x0009 09:18:53 INFO - Displays: 09:18:53 INFO - Display: 09:18:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:18:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:18:53 INFO - Display Serial Number: 1600x1200 60 09:18:53 INFO - Main Display: Yes 09:18:53 INFO - Mirror: Off 09:18:53 INFO - Online: Yes 09:18:53 INFO - Rotation: Supported 09:18:53 INFO - Return code: 0 09:18:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:18:53 INFO - Python 2.7.3 09:18:53 INFO - Return code: 0 09:18:53 INFO - grabbing minidump binary from tooltool 09:18:53 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')]} 09:18:53 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 0x10e178d30>, '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 0x10df9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e170b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:18:53 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 09:18:53 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 09:18:53 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 09:18:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:18:54 INFO - Return code: 0 09:18:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:18:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:18:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:18:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:18:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:18:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0344', '--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 09:18:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0344 --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 09:18:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39bIGHaPDr/Render', 09:18:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:54 INFO - 'HOME': '/Users/cltbld', 09:18:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:54 INFO - 'LOGNAME': 'cltbld', 09:18:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:18:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:18:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:54 INFO - 'MOZ_NO_REMOTE': '1', 09:18:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:18:54 INFO - 'NO_EM_RESTART': '1', 09:18:54 INFO - 'PAGER': '/bin/cat', 09:18:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:54 INFO - 'PWD': '/builds/slave/test', 09:18:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:18:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:54 INFO - 'SHELL': '/bin/bash', 09:18:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9OVJ0Xm7qS/Listeners', 09:18:54 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 09:18:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:54 INFO - 'USER': 'cltbld', 09:18:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:54 INFO - 'XPC_FLAGS': '0x0', 09:18:54 INFO - 'XPC_SERVICE_NAME': '0', 09:18:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0344', '--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 09:18:54 INFO - mozversion application_buildid: 20161029063803 09:18:54 INFO - mozversion application_changeset: 969c3295d3aa77931cca26eddb047d9d74bd9858 09:18:54 INFO - mozversion application_display_name: Nightly 09:18:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:18:54 INFO - mozversion application_name: Firefox 09:18:54 INFO - mozversion application_remotingname: firefox 09:18:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:18:54 INFO - mozversion application_vendor: Mozilla 09:18:54 INFO - mozversion application_version: 52.0a1 09:18:54 INFO - mozversion platform_buildid: 20161029063803 09:18:54 INFO - mozversion platform_changeset: 969c3295d3aa77931cca26eddb047d9d74bd9858 09:18:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:18:54 INFO - mozversion platform_version: 52.0a1 09:18:54 INFO - using testdate: 1477757934 09:18:54 INFO - actual date: 1477757934 09:18:54 INFO - starting webserver on 'localhost:49267' 09:18:54 INFO - SUITE-START | Running 2 tests 09:18:54 INFO - TEST-START | dromaeo_css 09:18:54 INFO - operating with platform_type : mac_ 09:18:54 INFO - Initialising browser for dromaeo_css test... 09:18:54 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmp7LY0nC/profile http://localhost:49267/getInfo.html) 09:18:57 INFO - PROCESS | 1653 | 2016-10-29 09:18:57.100 plugin-container[1655:7718] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 09:18:57 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:18:57 INFO - PROCESS | 1653 | 2016-10-29 09:18:57.103 plugin-container[1655:7718] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b07, name = 'com.apple.CFPasteboardClient' 09:18:57 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:18:57 INFO - PROCESS | 1653 | 2016-10-29 09:18:57.103 plugin-container[1655:7718] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:18:57 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 09:18:57 INFO - PROCESS | 1653 | colorDepth:24 09:18:57 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 09:18:57 INFO - PROCESS | 1653 | __metrics 09:18:57 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3131: TypeError: this.tabs is undefined 09:18:57 INFO - TEST-INFO | 1653: exit 0 09:18:57 INFO - Browser initialized. 09:18:57 INFO - Running cycle 1/1 for dromaeo_css test... 09:18:58 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmp7LY0nC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:00 INFO - PROCESS | 1656 | 2016-10-29 09:19:00.635 plugin-container[1657:7840] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8633, name = 'com.apple.tsm.portname' 09:19:00 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:00 INFO - PROCESS | 1656 | 2016-10-29 09:19:00.638 plugin-container[1657:7840] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4323, name = 'com.apple.CFPasteboardClient' 09:19:00 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:00 INFO - PROCESS | 1656 | 2016-10-29 09:19:00.638 plugin-container[1657:7840] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:19:10 INFO - PROCESS | 1656 | RSS: Main: 238460928 09:19:10 INFO - PROCESS | 1656 | 09:19:59 INFO - PROCESS | 1656 | 1477757999986 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] 09:22:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 09:22:15 INFO - PROCESS | 1656 | RSS: Main: 250474496 09:22:15 INFO - PROCESS | 1656 | 09:25:18 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 09:25:18 INFO - PROCESS | 1656 | RSS: Main: 263032832 09:25:18 INFO - PROCESS | 1656 | 09:28:20 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 09:28:21 INFO - PROCESS | 1656 | RSS: Main: 267214848 09:28:21 INFO - PROCESS | 1656 | 09:31:23 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 09:31:24 INFO - PROCESS | 1656 | RSS: Main: 265699328 09:31:24 INFO - PROCESS | 1656 | 09:34:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 09:34:27 INFO - PROCESS | 1656 | RSS: Main: 270106624 09:34:27 INFO - PROCESS | 1656 | 09:37:30 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 09:37:30 INFO - PROCESS | 1656 | RSS: Main: 271937536 09:37:30 INFO - PROCESS | 1656 | 09:37:30 INFO - PROCESS | 1656 | __start_tp_report 09:37:30 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 09:37:30 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 09:37:30 INFO - PROCESS | 1656 | |i|pagename|runs| 09:37:30 INFO - PROCESS | 1656 | |0;dojo.html;3224.7903886247395;3381.3913495570805;3411.249525104477;3416.043507817812;3429.314137172566;4851.1753001989655;4932.950670493294;5307.885344077748;5684.516816070629;5808.651480911149;5689.4373967179445;5707.286589176353;5765.596408251424;5782.335031471382;5823.621464604797;5235.921461178085;5257.86855328616;5271.472852714736;5349.22436246744;5387.676739395649;5377.059014672436;5387.973060134694;5401.378841433227;5416.539594134503;5418.864528386802;4748.596369308613;4767.594754445876;5334.226537152092;5368.3021207243;5411.702356370398;5164.173732202829;5186.844394668166;5247.580193584504;5251;5278.366596008504;5158.69047857126;5161.071007218699;5183.637145399824;5184.8963020739375;5231.346081739783;5094.847154585368;5154.814392689703;5654.6324488908085;5740.110282906115;5751.482366587027;5717.771289148429;5755.4244575542525;6156.199694039791;6230.875382492325;6273;6260.906086408708;6285.779997700104;6301.054841773725;6312.305646378894;6330.8100756977365;4616.8614941551805;5424.538914192299;5919.822405327847;5931.199288096138;5933.999999999957;6145.262568491809;6250.249970003629;6272.090546870678;6318.6524741138755;6319.9368006320265;6199.907001394983;6240.500759939194;6240.563160578716;6245.313015568284;6268.905966410507;5159.071367153948;5759.366469688331;6300.7479700811455;6314.589551679126;6321.778737744157;5641.928033673589;6140.504248697391;6161.691915404212;6197.907031394533;6224.502039836802;6176.166217560662;6244.2819075805955;6287.591306565058;6318.241810982711;6334.651594162366;4935.210366341368;4981.352424184833;5003.049420610072;5004.474530174352;5032.421271553818;3757.530308711411;4365.170617582649;4415.050764085673;4421.867343979686;4480.775961201927;5633.014222511044;5781.82654520365;5893.174955506232;5922.763089476459;5928.110783382527;6253.812385628438;6287.96856015717;6300.21247344082;6304.810855674337;6317.747290108436;5734.62724922887;5766.163906233573;5877.471027607536;5888.823335299948;5896.086106653475;5382.058139825514;5400.648957817729;5420.593455490854;5465.508104270555;5505.284313039279;6387.361263873576;6395.20059992501;6404.039394090923;6422.133012043314;6429.03564465334;6146.385361463817;6277.905831412624;6323.177986861679;6332.398422149889;6335.65153916539;6267.843303917256;6270.122182894307;6303.905441418474;6308.52686048539;6352.523560732871;6243.282022567281;6254.093156492192;6264.436200741773;6279.748810047547;6282.371762823681;6250.687465626609;6260.786527471806;6298.968505157628;6305.211848518935;6309.305976342691;6046.697665116814;6111.755529778759;6115.449609535163;6128.86980888409;6140.478059364826;6109.252844142575;6136.877262454636;6182.196314479089;6196.535259855439;6205.100260462117;4656.627469802475;5924.289085309791;5962.850928726816;5991.880162396815;6090.147379366981;5947.851303717442;6106.915739268716;6121.602095863755;6136.243373036893;6151.477124444473;6037.849053773691;6063.575549711478;6090.116933044772;6109.1010297800785;6128.938710612837 09:37:30 INFO - PROCESS | 1656 | |1;ext.html;467.7100197877316;494.0464902737717;498.0238732085113;499.38824939449177;553.070840987142;38951.22097558047;40387.39418908715;40852.59147408525;43344.78327608365;43468.3479747804;36694.26611467769;39182.80408597953;41602.37596436056;42078.57921420785;42300.57699423005;8053.919460805376;9418.717438476839;9612.90387096127;9620.374675646095;9642.710718678416;9430.764230894249;9498.667546635868;9520.761980950463;9524.952375238114;9570.760730981712;9508.857367139464;9562;9562.235021198323;9565.330426870121;9567;9446.527673616327;9528.285378596598;9536.380135291183;9541.952290238574;9553.996830332853;7782.610869456561;9263.6294548218;9288.256939444827;9338.439693618404;9343.953280233623;39703.80148099241;40025.59974400277;40040.3983840646;40095.39856902149;40098.39852402217;3380.7633465657414;3418.0942050356675;3567.8046221087184;3952.1898010907694;3964.2467931093;29276.41447171066;30254;30339.69660303413;30564;30626.84686576575;3147.3075923297065;3282.6224984126893;3317.419451595961;3332.783369081002;3346.4143774839304;376497;440687;443592.47426340246;446507;448300;56086.15870761942;56293;56411.71794141003;56511.43488565062;56552.434475654714;58970.70514647399;59084.40915590789;59390.0304984749;59543;59753.402465975654;15465.613359666098;15619.60950976212;15696.76454853178;15702.9214853925;15721.764173537405;10619.150467962545;12182.147249692614;12234.265944043385;12269.631911042683;12273.815892761615;14348.06737562055;14871;14876.62808429798;14891.478798242226;15045.924770376078;5674.319081710221;6795.52431329812;6983.336583024605;7019.3682568569075;7032.753853615099;831.1812864328631;851.595492141228;955.7610597350663;1078.5200585739428;1094.3324572011068;29982.25044373908;30121.397572048438;30609.693903060685;30625.69374306273;30847.064469685192;1049.0872940541778;1060.591672206206;1089.5260561655705;1199.6640940536663;1237.7524495101015;13024.869751302555;13313.334333283297;13520.918326533854;15247.23763811805;15466.922665386826;588463;656544;657907;661319.6934015299;668892;57992.8401431969;58180;58217;58268.708656456445;58324.292706339314;1231.6859200903712;1350.210127075652;1384.6538365408649;1406.4585134723204;1408.2184387664945;23734;24549.509009819347;28144;29194.56208156922;30562.388752224386;47851;48440.27339589979;49350.27274045535;49420;49434.752826235635;589679;595312;599138;600704;603341;25980.480390392466;26267.4746505065;26291.21126366212;26379.076732314275;26409.73590264073;39237.80381098076;40413.989650258976;40443;40559;40690.79654601708;34730.652693473756;37479.625203747615;37839.62160378471;39029.21941561096;40500;33677;37519.43720844135;37573.62426375701;38631.798727416186;38763.612363876004;28683.28291792722;32054.038378848672;32757.836210818794;36528;37796.24407511779;27134.907117677223;30648;31310.686893130776;31935.68064319327;34066;22924.19765308206;25550;25642.384370813434;25781.87109064443;25862.74137258603 09:37:30 INFO - PROCESS | 1656 | |2;jquery.html;364.6007621654291;367.36262584416033;371.3538443108989;375.2551185895994;391.62795344422807;19347.322843700473;20029.098690558923;20044;20700.482487937792;20826.375208743724;11866.22869513481;12042.81935770964;12247.755044899097;12331.568395106173;12384.752304953896;5229.8954020919555;5581.274434323533;5584.4136365681625;5585.636933599303;5591.6365436246715;3897.4738410314526;4130.5043394792665;5387.757550910218;5900.144479050535;6152.3847615238565;6189.102580125879;6191.81424557264;6201.193844800192;6204.348543402945;6215.192025036761;6393;6808.012838138442;6813.693383797741;6849.075374824385;6851.691673874688;5797.217375654275;5954.315253745788;5959.612625179349;5990.101484777319;6083.209182806251;13276.46894124234;13313.800292995613;13395.598132056053;13466.124701894443;13523.797143042862;2030.5735795482967;2189.0477642225524;2235.10595761695;2238;2945.896893608735;9912.355696879751;10043.096121349114;10156.933521980234;10350.689479315633;10517.526711297933;1790.355472030068;1828.4606041217874;1880.0693656639914;1880.0975531744702;1903.1816318982833;111099;115436.42281788537;117401;117475;118106.22840657311;11305.208635395607;11472.369019703829;11551.884481155248;11567.363794991355;11847.815218478083;11773.587924422776;12276.754464910831;12925.54760583375;13313.600591982256;14484.493042743454;8283.50298982063;8384.622691978875;8394.160583941679;8414.537200453911;8552.61513231906;7325.816854578678;8144.389170812213;8241.50550966944;8384.245417912296;8385.45494542865;3797;3869.7423337415453;4553.635709143261;4562.543745625478;4655.767211639472;1608.8898659924614;1657.544175351764;1694.7118989771768;1905.59982403694;1970.5073731567109;863.9438436501607;914.7758799094264;934.3319526538558;992.806402751471;1027.994860025695;18165.909170454062;20636.277730279668;20975.475613109793;22082.116715331205;22410.77589224087;851.9957400212959;908.795521007768;942.2320808541155;993.9503024848729;996.5400687992127;10387.013233742782;11051.889481105087;11293.943530282297;11571.611406631258;11711.01369150878;358894;554386;568270;585872;586219;131562.34218829035;133256;133540;133853;136424;943.7782121201512;956.6125719083782;971.8299297622888;980.6077568972468;981.1856159387692;19264.711029334852;19485.830850149036;19487.415377538695;19509.60980780348;19551.826890386623;51056.48943510517;51942.48057519377;52717;53483.732581336844;53756.46243537514;73297.26702732904;76800;77321.61339193268;77992;78289.21710783047;6993.370596646327;7352.632368381666;7405.740799072007;7546.24537546241;7675.309222170032;7928.960355198187;13013.024023198488;13018.28399438002;13469.663258418617;13684.110532815335;11368.928061076398;11409.714757131138;11416.086713063101;11661.008814250883;11742.471588778355;10897.618583349546;11267.943660281646;11554.47415525327;11726.70683232926;11762.70593235176;11363.602273920373;11435.828462573234;11456.82814757796;11521.366324852213;11957.94021029924;7444.255574442729;7503.174650788518;7603.9619801900635;7637.312641862259;7639.923600763921;9274.44353338802;11678.766424671629;11748.177627565989;11814.173007889365;11820.995215406787 09:37:30 INFO - PROCESS | 1656 | |3;mootools.html;1426.5934208750505;1534.278888922206;1764.691179043667;1887.820657037581;1911.7514723085997;7457.62711864406;7685.615719214031;8017.278444939961;8507.957460212698;8509.53197574134;7937.325327347168;8234.135415781331;8263.752087437371;8333.166683331663;8339.583020848964;2255.064148378425;2272.7045484087043;2279.2706333973133;2288.141946769961;2289.027163455533;2266.841321107515;2297.3452566018686;2327.2552783109413;2328.988355058222;2331.5220379822094;2261.072960086365;2277.1574517428494;2285.451491642002;2291.140822191678;2294.7705229477087;2230.1413955627118;2246.3036458697857;2261.796438320551;2263.4228271790753;2275.1354485295565;1957.618264438435;2265.32040387883;2289.8053665438456;2297.5634629420356;2312.2022902098747;6592.604443733391;7240.384567311785;7617.46677732559;8375.664973401057;8384.622691978875;5013.949860501385;5185.274061631375;5188.9881473112755;5201.063808514466;5233.371995360543;3176.253580408625;3262.5432439458496;3510.701590364822;3511.315293517765;3526.1537231064617;1020.9489525523694;1039.2880876599554;1046.9581216751321;1060.199549340251;1063.8936106389376;10553.52509137083;10565.63020294286;10608.62869799561;10740.08709259714;10756;4387.188370151533;4423.955760442354;4634.907301854011;4636.142313671981;4644.210484217691;8741.956290218508;9223.354365194373;9296.907030929604;9309.767255818524;9312.674056408128;5161.664491808096;5166.147585648326;5169.844904652867;5170.663906846043;5185.818496352609;3591.964080359215;4482.641388688897;4676.836310729108;4748.169070412664;4754.429468463806;3695.113172838553;3711.294853977736;3764.0966168119453;3798.6581207691443;3815.275097731422;1220.8107743299802;1240.274439452912;1245.6574442028334;1248.7377650693456;1251.249250449741;905.2079430498314;919.9448033118034;924.5562130177552;945.4658118163215;954.6801821389773;5595.38450770415;5737.311522617312;5852.297724273115;5854.360778981807;5873.706314684248;733.7541923455595;864.6454953469048;886.9932626469005;919.2783664823082;953.4565297780199;3297.9446577095578;3555;3657.00693122249;3849.9230015400094;3937.838337690388;9330.393524420891;9959.950200248952;10008.249381296431;10022.799544009078;10032.899671003197;7418.591977441185;8930.732078037669;9898.802023959624;10005.399676019462;10021.549030293654;6519.8696026079215;6705.396514313735;6798.7280508779095;7902.881456778153;7970.402219833536;5529.115341545334;5538.307711536058;5573.052581061148;5632.831015069554;5689.516391106802;7788.026496687914;9246.306527010367;9402.15380615748;9424.29317801154;9447.385919915183;9168.54157292146;9382.953085234803;9399;9470.95264523673;9578.425294482353;6260.40526150015;6445;6556.475481961336;6752.561083529555;6790.558613690095;6720.899186512109;7730.729424470116;7999.000124984377;8009.759707208793;8013.639386227517;6598.505112116514;6779.661016949231;7045.894311585433;7161.104861892263;7273.927260727537;7031.452561877987;7032.050673159058;7054.435645148274;7055.223925368308;7137.607431591312;6594.241662208908;6629.303923088007;6697.598144111369;6712.463002959849;6714.2278637955305;4774.522547745198;4823.252395878644;4836.830710925101;4870;4925.532074452921;4121.381792731115;4742.193827049479;4799.952000479951;4934.407871055496;5014.398272207359 09:37:30 INFO - PROCESS | 1656 | |4;prototype.html;292.0930510764076;292.8140630699506;301.4393228594814;306.119905272342;309.2762934732725;4338.8915277118085;5275.683458992463;5459.69971651559;5466.480684334992;5491.615586908913;4363.585459381354;4909.797099710576;5441.673499590027;5454.045542030149;5479.424660410659;4161.708680392374;4170.603792639709;4171.770552619605;4173.332266837307;4190.413342132104;3611.5305010348748;4115.485564304462;4185.91628167438;4201.852935147271;4215.873523794291;3412.4198886189406;3524.0926235855395;3807.4859893914245;3859.807009649506;3931.2309460742636;4096.955276404513;4113.568075352075;4129;4131.876043718678;4132.912878996088;3553.6446355364255;3554.680078792895;3579.838907249154;3584.5160903278247;3608.2422691234874;4518.289026584056;4781.760911954389;5120.874836942664;5337.332833395825;5357.19642053695;4629.583337499607;4656.837010704643;4663.44038715353;4673.789679464398;4688.367070445481;4510.323451482271;4736.3369128322065;5797.507211886952;5815.79644712433;5845.181674565564;2999.505081661545;3432.8843125984154;3649.288388764192;3818.522684664417;3916.138449541096;5092.337996060489;5152.098382783035;5689.6017278790505;6183.845403864894;6200.87598248037;3640.5995340512522;3794.582595914447;3832.827522761482;3972.225416043844;3975.2906250312644;3438.157651375429;3890.2997460456822;4023.7183397161916;4030.4760381150263;4040.8685568040996;4478.395416618715;4626.2705004047775;4641.744704041243;4683.7892294846815;4700.45944716362;4751.7882939850115;5840.707964601753;5867.157012157044;5879.588428810028;5915.911261331084;960.8798900137483;1308.8036794480904;1324.966875828112;1347.622665653599;1358.6875018745743;877.3025444771422;892.9642814287356;901.4275934781462;913.1370854542394;914.8719179314901;4640.95578494836;4769.833055843029;4786.617070634411;5153.072446959508;5404.594805350637;5063.594912406998;5815.156802263648;5923;5976.760929562855;6037.969810151009;4324.459442569679;5254.369475662945;5421.241026256327;5446.759178288912;5468.562514998791;2311.9653205201935;2405.5102968427595;2564.1153801938303;2624.2604288888256;2633.289011966758;4295.1437294199;4774.7135171889795;4806.077568972369;5094.923576146361;5172.456892026283;4040.5151381834366;4064.309067458539;4499;5041.663959050855;5065.316182315414;4457.866264012085;4680.455449701591;4728.976355118202;5105.166632518987;5158.561522270499;4928.67963582366;4930.8767280818265;4932.753362331798;5006.524380183877;5037.614655969491;3934.7639141651593;4442.4669039714145;4512.571305725951;4574.748388838512;4920.630952678635;4313.288307429365;4333.544977777288;5041.6722913010535;5080.282049103859;5097.643164978416;3012.328157872402;3423.134391756449;3431.5024321473747;3436.8281585921104;3470.015786423724;4251.489821221473;4605.30920361935;4825.686330388514;4985.651004429655;5251.6323857331145;4592.563706447882;5753.021986262262;5758.395368486249;5763.538916886555;5801.50535269838;5682.602217844711;5712.714364281853;5803.9419605803405;5809.8503553685405;5842.912356314574;4678.321643361762;5609.49514543693;5669.85825354353;5712.715188766393;5799.217105690678;5870.090136028923;5901.675407852609;5907.586468947133;5946.524278057658;5951.404859514014;5453.7818487260065;5773.91339129905;5809.331926828299;5812.796552120655;5843.766249349978 09:37:30 INFO - PROCESS | 1656 | |5;yui.html;715.871143194225;743.0748717846283;760.9733659321925;768.4313607930134;839.576014112873;14477.05899116558;15175.696486070272;15191.848081519209;15245.92377038116;15247.0851748895;13891.374888130033;14018.859811401882;14048.508302209424;14060.296985150753;14098.64753381166;681.7239018197638;701.9438444924419;845.8984921809391;864.7578677970179;875.234170101163;797.6769408389582;890.3055616619048;891.8974317953454;896.2874514760748;896.7847716548047;808.7129069180415;941.722191953372;942.3591957468918;942.4392486470573;943.6602822983721;901.5897766516252;928.401181238101;934.6168071090854;935.4293880732747;938.2869019545127;690.492488021304;697.7174244431013;698.179638924267;698.3470455124475;698.5250029979612;14452.927735361362;14455.060421072596;14580.854191458056;14662.193579353132;15078.924605377013;14068.859311406857;14170.433182672681;14259.358328875225;14260.768916073868;14268.714625707427;11244.550217991271;11431.771364572662;11445.370504622242;11462;11616.477258523386;3723.1995121048835;3724.2923844469733;3842.53889533255;3881.7670939743425;3924;63268.41828954313;88557.55721221417;89356.55321723415;89368;96102.55846162314;10866.782664346669;10925.781484370267;10928.125749939985;11005.669829905115;11285.774284514428;21950.231741889278;22377.440563985703;22408.103675853108;22425;22448.32655020352;7622.695092196361;7626.084869815547;7655.349295309849;7947.721829735931;7997.6800927963395;3654.744167908221;4133.318002529609;4193.433886425354;4257.106007738348;4311.8490852820005;3447.1899103710607;3522.947155792665;3529.982350088233;3564.2515071834687;3577.069961809948;634.3656343656344;662.1491383572087;663.5985228936424;674.9730010799611;683.8256244657581;526.210683974039;577.2149876168407;599.2958274028;631.9399657032575;651.2835880531378;8487.575621219037;8787.824243515093;9200.125988031126;9271.490068046187;9444;623.7006237006262;655.9868802623921;657.3853447027045;668.7859884836805;673.1686367336335;4335.458067741532;4496;4567.520410356865;4625.167469855391;4629.579079543604;47543.04913901703;66566.33433665601;108438;109706;112227;60657.09014364788;88056;102478;103242.48378758058;105185;51267.743661281456;59488.30701579018;60502;71865.92201116988;80153;8299.792505187419;8435.873461897954;9582.904170958202;9891.604335826774;9911.405315681082;51943.74028129835;53165.73417133044;54026.656841707205;55370.72314638401;56391.308260752245;57642;62162.37837621747;77963;78633.60683196547;79851;11014.614488492864;11056.226064175431;11059.944700276446;11204.551817927191;11452.770944581229;11967.940160299491;12042.698932526757;12060.51757929673;12110.939445302716;12451.128421010442;11373.33207810815;11646.88353116458;11710.238834475731;11710.355930423568;12021.459034343301;11676.591319304123;11712.472938717608;11760.235584686969;11767.52929882795;12036.518539258683;11381.317120972768;11397.886021139682;11687.123465739933;11849.940750296539;12010.939945300217;9697.854532182164;9841.753956150867;9959;10029.69910902645;10181.083702466518;9477.620895164117;10022.148117410103;10030.147437467605;10048.246381521269;10054.24593155531 09:37:30 INFO - PROCESS | 1656 | __end_tp_report 09:37:30 INFO - PROCESS | 1656 | __start_cc_report 09:37:30 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,366 09:37:30 INFO - PROCESS | 1656 | __end_cc_report 09:37:30 INFO - PROCESS | 1656 | __startTimestamp1477759050488__endTimestamp 09:37:30 INFO - PROCESS | 1656 | 09:37:30 INFO - PROCESS | 1656 | ------- Summary: start ------- 09:37:30 INFO - PROCESS | 1656 | Number of tests: 6 09:37:30 INFO - PROCESS | 1656 | 09:37:30 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5735.06 Median:5947.85 stddev:678.49 (11.4%) stddev-sans-first:651.37 09:37:30 INFO - PROCESS | 1656 | Values: 3224.8 3381.4 3411.2 3416.0 3429.3 4851.2 4933.0 5307.9 5684.5 5808.7 5689.4 5707.3 5765.6 5782.3 5823.6 5235.9 5257.9 5271.5 5349.2 5387.7 5377.1 5388.0 5401.4 5416.5 5418.9 4748.6 4767.6 5334.2 5368.3 5411.7 5164.2 5186.8 5247.6 5251.0 5278.4 5158.7 5161.1 5183.6 5184.9 5231.3 5094.8 5154.8 5654.6 5740.1 5751.5 5717.8 5755.4 6156.2 6230.9 6273.0 6260.9 6285.8 6301.1 6312.3 6330.8 4616.9 5424.5 5919.8 5931.2 5934.0 6145.3 6250.2 6272.1 6318.7 6319.9 6199.9 6240.5 6240.6 6245.3 6268.9 5159.1 5759.4 6300.7 6314.6 6321.8 5641.9 6140.5 6161.7 6197.9 6224.5 6176.2 6244.3 6287.6 6318.2 6334.7 4935.2 4981.4 5003.0 5004.5 5032.4 3757.5 4365.2 4415.1 4421.9 4480.8 5633.0 5781.8 5893.2 5922.8 5928.1 6253.8 6288.0 6300.2 6304.8 6317.7 5734.6 5766.2 5877.5 5888.8 5896.1 5382.1 5400.6 5420.6 5465.5 5505.3 6387.4 6395.2 6404.0 6422.1 6429.0 6146.4 6277.9 6323.2 6332.4 6335.7 6267.8 6270.1 6303.9 6308.5 6352.5 6243.3 6254.1 6264.4 6279.7 6282.4 6250.7 6260.8 6299.0 6305.2 6309.3 6046.7 6111.8 6115.4 6128.9 6140.5 6109.3 6136.9 6182.2 6196.5 6205.1 4656.6 5924.3 5962.9 5991.9 6090.1 5947.9 6106.9 6121.6 6136.2 6151.5 6037.8 6063.6 6090.1 6109.1 6128.9 09:37:30 INFO - PROCESS | 1656 | 09:37:30 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:68338.57 Median:21834.02 stddev:151702.25 (694.8%) stddev-sans-first:152042.23 09:37:30 INFO - PROCESS | 1656 | Values: 467.7 494.0 498.0 499.4 553.1 38951.2 40387.4 40852.6 43344.8 43468.3 36694.3 39182.8 41602.4 42078.6 42300.6 8053.9 9418.7 9612.9 9620.4 9642.7 9430.8 9498.7 9520.8 9525.0 9570.8 9508.9 9562.0 9562.2 9565.3 9567.0 9446.5 9528.3 9536.4 9542.0 9554.0 7782.6 9263.6 9288.3 9338.4 9344.0 39703.8 40025.6 40040.4 40095.4 40098.4 3380.8 3418.1 3567.8 3952.2 3964.2 29276.4 30254.0 30339.7 30564.0 30626.8 3147.3 3282.6 3317.4 3332.8 3346.4 376497.0 440687.0 443592.5 446507.0 448300.0 56086.2 56293.0 56411.7 56511.4 56552.4 58970.7 59084.4 59390.0 59543.0 59753.4 15465.6 15619.6 15696.8 15702.9 15721.8 10619.2 12182.1 12234.3 12269.6 12273.8 14348.1 14871.0 14876.6 14891.5 15045.9 5674.3 6795.5 6983.3 7019.4 7032.8 831.2 851.6 955.8 1078.5 1094.3 29982.3 30121.4 30609.7 30625.7 30847.1 1049.1 1060.6 1089.5 1199.7 1237.8 13024.9 13313.3 13520.9 15247.2 15466.9 588463.0 656544.0 657907.0 661319.7 668892.0 57992.8 58180.0 58217.0 58268.7 58324.3 1231.7 1350.2 1384.7 1406.5 1408.2 23734.0 24549.5 28144.0 29194.6 30562.4 47851.0 48440.3 49350.3 49420.0 49434.8 589679.0 595312.0 599138.0 600704.0 603341.0 25980.5 26267.5 26291.2 26379.1 26409.7 39237.8 40414.0 40443.0 40559.0 40690.8 34730.7 37479.6 37839.6 39029.2 40500.0 33677.0 37519.4 37573.6 38631.8 38763.6 28683.3 32054.0 32757.8 36528.0 37796.2 27134.9 30648.0 31310.7 31935.7 34066.0 22924.2 25550.0 25642.4 25781.9 25862.7 09:37:30 INFO - PROCESS | 1656 | 09:37:30 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:32613.94 Median:12324.39 stddev:90638.51 (735.4%) stddev-sans-first:90860.43 09:37:30 INFO - PROCESS | 1656 | Values: 364.6 367.4 371.4 375.3 391.6 19347.3 20029.1 20044.0 20700.5 20826.4 11866.2 12042.8 12247.8 12331.6 12384.8 5229.9 5581.3 5584.4 5585.6 5591.6 3897.5 4130.5 5387.8 5900.1 6152.4 6189.1 6191.8 6201.2 6204.3 6215.2 6393.0 6808.0 6813.7 6849.1 6851.7 5797.2 5954.3 5959.6 5990.1 6083.2 13276.5 13313.8 13395.6 13466.1 13523.8 2030.6 2189.0 2235.1 2238.0 2945.9 9912.4 10043.1 10156.9 10350.7 10517.5 1790.4 1828.5 1880.1 1880.1 1903.2 111099.0 115436.4 117401.0 117475.0 118106.2 11305.2 11472.4 11551.9 11567.4 11847.8 11773.6 12276.8 12925.5 13313.6 14484.5 8283.5 8384.6 8394.2 8414.5 8552.6 7325.8 8144.4 8241.5 8384.2 8385.5 3797.0 3869.7 4553.6 4562.5 4655.8 1608.9 1657.5 1694.7 1905.6 1970.5 863.9 914.8 934.3 992.8 1028.0 18165.9 20636.3 20975.5 22082.1 22410.8 852.0 908.8 942.2 994.0 996.5 10387.0 11051.9 11293.9 11571.6 11711.0 358894.0 554386.0 568270.0 585872.0 586219.0 131562.3 133256.0 133540.0 133853.0 136424.0 943.8 956.6 971.8 980.6 981.2 19264.7 19485.8 19487.4 19509.6 19551.8 51056.5 51942.5 52717.0 53483.7 53756.5 73297.3 76800.0 77321.6 77992.0 78289.2 6993.4 7352.6 7405.7 7546.2 7675.3 7929.0 13013.0 13018.3 13469.7 13684.1 11368.9 11409.7 11416.1 11661.0 11742.5 10897.6 11267.9 11554.5 11726.7 11762.7 11363.6 11435.8 11456.8 11521.4 11957.9 7444.3 7503.2 7604.0 7637.3 7639.9 9274.4 11678.8 11748.2 11814.2 11821.0 09:37:30 INFO - PROCESS | 1656 | 09:37:30 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5324.43 Median:5185.55 stddev:2883.44 (55.6%) stddev-sans-first:2876.65 09:37:30 INFO - PROCESS | 1656 | Values: 1426.6 1534.3 1764.7 1887.8 1911.8 7457.6 7685.6 8017.3 8508.0 8509.5 7937.3 8234.1 8263.8 8333.2 8339.6 2255.1 2272.7 2279.3 2288.1 2289.0 2266.8 2297.3 2327.3 2329.0 2331.5 2261.1 2277.2 2285.5 2291.1 2294.8 2230.1 2246.3 2261.8 2263.4 2275.1 1957.6 2265.3 2289.8 2297.6 2312.2 6592.6 7240.4 7617.5 8375.7 8384.6 5013.9 5185.3 5189.0 5201.1 5233.4 3176.3 3262.5 3510.7 3511.3 3526.2 1020.9 1039.3 1047.0 1060.2 1063.9 10553.5 10565.6 10608.6 10740.1 10756.0 4387.2 4424.0 4634.9 4636.1 4644.2 8742.0 9223.4 9296.9 9309.8 9312.7 5161.7 5166.1 5169.8 5170.7 5185.8 3592.0 4482.6 4676.8 4748.2 4754.4 3695.1 3711.3 3764.1 3798.7 3815.3 1220.8 1240.3 1245.7 1248.7 1251.2 905.2 919.9 924.6 945.5 954.7 5595.4 5737.3 5852.3 5854.4 5873.7 733.8 864.6 887.0 919.3 953.5 3297.9 3555.0 3657.0 3849.9 3937.8 9330.4 9960.0 10008.2 10022.8 10032.9 7418.6 8930.7 9898.8 10005.4 10021.5 6519.9 6705.4 6798.7 7902.9 7970.4 5529.1 5538.3 5573.1 5632.8 5689.5 7788.0 9246.3 9402.2 9424.3 9447.4 9168.5 9383.0 9399.0 9471.0 9578.4 6260.4 6445.0 6556.5 6752.6 6790.6 6720.9 7730.7 7999.0 8009.8 8013.6 6598.5 6779.7 7045.9 7161.1 7273.9 7031.5 7032.1 7054.4 7055.2 7137.6 6594.2 6629.3 6697.6 6712.5 6714.2 4774.5 4823.3 4836.8 4870.0 4925.5 4121.4 4742.2 4800.0 4934.4 5014.4 09:37:30 INFO - PROCESS | 1656 | 09:37:30 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4391.94 Median:4714.72 stddev:1371.90 (29.1%) stddev-sans-first:1340.79 09:37:30 INFO - PROCESS | 1656 | Values: 292.1 292.8 301.4 306.1 309.3 4338.9 5275.7 5459.7 5466.5 5491.6 4363.6 4909.8 5441.7 5454.0 5479.4 4161.7 4170.6 4171.8 4173.3 4190.4 3611.5 4115.5 4185.9 4201.9 4215.9 3412.4 3524.1 3807.5 3859.8 3931.2 4097.0 4113.6 4129.0 4131.9 4132.9 3553.6 3554.7 3579.8 3584.5 3608.2 4518.3 4781.8 5120.9 5337.3 5357.2 4629.6 4656.8 4663.4 4673.8 4688.4 4510.3 4736.3 5797.5 5815.8 5845.2 2999.5 3432.9 3649.3 3818.5 3916.1 5092.3 5152.1 5689.6 6183.8 6200.9 3640.6 3794.6 3832.8 3972.2 3975.3 3438.2 3890.3 4023.7 4030.5 4040.9 4478.4 4626.3 4641.7 4683.8 4700.5 4751.8 5840.7 5867.2 5879.6 5915.9 960.9 1308.8 1325.0 1347.6 1358.7 877.3 893.0 901.4 913.1 914.9 4641.0 4769.8 4786.6 5153.1 5404.6 5063.6 5815.2 5923.0 5976.8 6038.0 4324.5 5254.4 5421.2 5446.8 5468.6 2312.0 2405.5 2564.1 2624.3 2633.3 4295.1 4774.7 4806.1 5094.9 5172.5 4040.5 4064.3 4499.0 5041.7 5065.3 4457.9 4680.5 4729.0 5105.2 5158.6 4928.7 4930.9 4932.8 5006.5 5037.6 3934.8 4442.5 4512.6 4574.7 4920.6 4313.3 4333.5 5041.7 5080.3 5097.6 3012.3 3423.1 3431.5 3436.8 3470.0 4251.5 4605.3 4825.7 4985.7 5251.6 4592.6 5753.0 5758.4 5763.5 5801.5 5682.6 5712.7 5803.9 5809.9 5842.9 4678.3 5609.5 5669.9 5712.7 5799.2 5870.1 5901.7 5907.6 5946.5 5951.4 5453.8 5773.9 5809.3 5812.8 5843.8 09:37:30 INFO - PROCESS | 1656 | 09:37:30 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:19059.88 Median:4627.37 stddev:27378.68 (591.7%) stddev-sans-first:27420.83 09:37:30 INFO - PROCESS | 1656 | Values: 715.9 743.1 761.0 768.4 839.6 14477.1 15175.7 15191.8 15245.9 15247.1 13891.4 14018.9 14048.5 14060.3 14098.6 681.7 701.9 845.9 864.8 875.2 797.7 890.3 891.9 896.3 896.8 808.7 941.7 942.4 942.4 943.7 901.6 928.4 934.6 935.4 938.3 690.5 697.7 698.2 698.3 698.5 14452.9 14455.1 14580.9 14662.2 15078.9 14068.9 14170.4 14259.4 14260.8 14268.7 11244.6 11431.8 11445.4 11462.0 11616.5 3723.2 3724.3 3842.5 3881.8 3924.0 63268.4 88557.6 89356.6 89368.0 96102.6 10866.8 10925.8 10928.1 11005.7 11285.8 21950.2 22377.4 22408.1 22425.0 22448.3 7622.7 7626.1 7655.3 7947.7 7997.7 3654.7 4133.3 4193.4 4257.1 4311.8 3447.2 3522.9 3530.0 3564.3 3577.1 634.4 662.1 663.6 675.0 683.8 526.2 577.2 599.3 631.9 651.3 8487.6 8787.8 9200.1 9271.5 9444.0 623.7 656.0 657.4 668.8 673.2 4335.5 4496.0 4567.5 4625.2 4629.6 47543.0 66566.3 108438.0 109706.0 112227.0 60657.1 88056.0 102478.0 103242.5 105185.0 51267.7 59488.3 60502.0 71865.9 80153.0 8299.8 8435.9 9582.9 9891.6 9911.4 51943.7 53165.7 54026.7 55370.7 56391.3 57642.0 62162.4 77963.0 78633.6 79851.0 11014.6 11056.2 11059.9 11204.6 11452.8 11967.9 12042.7 12060.5 12110.9 12451.1 11373.3 11646.9 11710.2 11710.4 12021.5 11676.6 11712.5 11760.2 11767.5 12036.5 11381.3 11397.9 11687.1 11849.9 12010.9 9697.9 9841.8 9959.0 10029.7 10181.1 9477.6 10022.1 10030.1 10048.2 10054.2 09:37:30 INFO - PROCESS | 1656 | -------- Summary: end -------- 09:37:30 INFO - PROCESS | 1656 | 09:37:31 INFO - TEST-INFO | 1656: exit 0 09:37:31 INFO - TEST-OK | dromaeo_css | took 1116886ms 09:37:31 INFO - TEST-START | kraken 09:37:31 INFO - operating with platform_type : mac_ 09:37:31 INFO - Initialising browser for kraken test... 09:37:31 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmp1c_BEo/profile http://localhost:49267/getInfo.html) 09:37:33 INFO - PROCESS | 1669 | 2016-10-29 09:37:33.892 plugin-container[1670:10242] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4537, name = 'com.apple.tsm.portname' 09:37:33 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:37:33 INFO - PROCESS | 1669 | 2016-10-29 09:37:33.896 plugin-container[1670:10242] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:37:33 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:37:33 INFO - PROCESS | 1669 | 2016-10-29 09:37:33.896 plugin-container[1670:10242] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:37:33 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 09:37:33 INFO - PROCESS | 1669 | colorDepth:24 09:37:33 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 09:37:33 INFO - PROCESS | 1669 | __metrics 09:37:34 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3131: TypeError: this.tabs is undefined 09:37:34 INFO - TEST-INFO | 1669: exit 0 09:37:34 INFO - Browser initialized. 09:37:34 INFO - Running cycle 1/1 for kraken test... 09:37:34 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmp1c_BEo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:37 INFO - PROCESS | 1671 | 2016-10-29 09:37:37.395 plugin-container[1672:10355] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 09:37:37 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:37:37 INFO - PROCESS | 1671 | 2016-10-29 09:37:37.395 plugin-container[1672:10355] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:37:37 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:37:37 INFO - PROCESS | 1671 | 2016-10-29 09:37:37.395 plugin-container[1672:10355] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:37:46 INFO - PROCESS | 1671 | RSS: Main: 238407680 09:37:46 INFO - PROCESS | 1671 | 09:37:49 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 09:37:50 INFO - PROCESS | 1671 | RSS: Main: 250667008 09:37:50 INFO - PROCESS | 1671 | 09:37:53 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 09:37:53 INFO - PROCESS | 1671 | RSS: Main: 258232320 09:37:53 INFO - PROCESS | 1671 | 09:37:57 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 09:37:58 INFO - PROCESS | 1671 | RSS: Main: 261099520 09:37:58 INFO - PROCESS | 1671 | 09:38:00 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 09:38:00 INFO - PROCESS | 1671 | RSS: Main: 262316032 09:38:00 INFO - PROCESS | 1671 | 09:38:02 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:38:03 INFO - PROCESS | 1671 | RSS: Main: 262684672 09:38:03 INFO - PROCESS | 1671 | 09:38:06 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 09:38:06 INFO - PROCESS | 1671 | RSS: Main: 262905856 09:38:06 INFO - PROCESS | 1671 | 09:38:10 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 09:38:10 INFO - PROCESS | 1671 | RSS: Main: 264282112 09:38:10 INFO - PROCESS | 1671 | 09:38:13 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 09:38:14 INFO - PROCESS | 1671 | RSS: Main: 264560640 09:38:14 INFO - PROCESS | 1671 | 09:38:15 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:38:16 INFO - PROCESS | 1671 | RSS: Main: 264237056 09:38:16 INFO - PROCESS | 1671 | 09:38:18 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:38:18 INFO - PROCESS | 1671 | RSS: Main: 264257536 09:38:18 INFO - PROCESS | 1671 | 09:38:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:38:21 INFO - PROCESS | 1671 | RSS: Main: 264380416 09:38:21 INFO - PROCESS | 1671 | 09:38:24 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:38:25 INFO - PROCESS | 1671 | RSS: Main: 264617984 09:38:25 INFO - PROCESS | 1671 | 09:38:28 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:38:29 INFO - PROCESS | 1671 | RSS: Main: 264691712 09:38:29 INFO - PROCESS | 1671 | 09:38:30 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 09:38:31 INFO - PROCESS | 1671 | RSS: Main: 266371072 09:38:31 INFO - PROCESS | 1671 | 09:38:31 INFO - PROCESS | 1671 | __start_tp_report 09:38:31 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 09:38:31 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 09:38:31 INFO - PROCESS | 1671 | |i|pagename|runs| 09:38:31 INFO - PROCESS | 1671 | |0;ai-astar;111;121;118;116;118;118;119;118;107;118 09:38:31 INFO - PROCESS | 1671 | |1;audio-beat-detection;152;146;148;185;149;115;117;119;162;114 09:38:31 INFO - PROCESS | 1671 | |2;audio-dft;198;201;207;269;284;219;196;198;255;246 09:38:31 INFO - PROCESS | 1671 | |3;audio-fft;76;61;62;100;73;85;82;74;80;60 09:38:31 INFO - PROCESS | 1671 | |4;audio-oscillator;86;70;90;72;70;70;75;72;66;85 09:38:31 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;91;89;91;90;127;88;91;89;93;90 09:38:31 INFO - PROCESS | 1671 | |6;imaging-darkroom;97;96;114;154;99;152;155;103;143;151 09:38:31 INFO - PROCESS | 1671 | |7;imaging-desaturate;104;97;87;87;102;87;90;93;89;88 09:38:31 INFO - PROCESS | 1671 | |8;json-parse-financial;58;58;56;57;58;58;58;60;58;65 09:38:31 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;88;74;70;81;73;68;70;64;76;63 09:38:31 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;93;90;90;84;89;102;110;111;91;90 09:38:31 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;140;154;160;147;164;169;168;151;190;152 09:38:31 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;222;174;173;173;174;175;176;176;176;178 09:38:31 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;68;70;67;68;68;68;69;67;68;84 09:38:31 INFO - PROCESS | 1671 | __end_tp_report 09:38:31 INFO - PROCESS | 1671 | __start_cc_report 09:38:31 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,946 09:38:31 INFO - PROCESS | 1671 | __end_cc_report 09:38:31 INFO - PROCESS | 1671 | __startTimestamp1477759111023__endTimestamp 09:38:31 INFO - PROCESS | 1671 | 09:38:31 INFO - PROCESS | 1671 | ------- Summary: start ------- 09:38:31 INFO - PROCESS | 1671 | Number of tests: 14 09:38:31 INFO - PROCESS | 1671 | 09:38:31 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:116.40 Median:118.00 stddev:4.20 (3.6%) stddev-sans-first:3.97 09:38:31 INFO - PROCESS | 1671 | Values: 111.0 121.0 118.0 116.0 118.0 118.0 119.0 118.0 107.0 118.0 09:38:31 INFO - PROCESS | 1671 | 09:38:31 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:140.70 Median:148.50 stddev:23.80 (16.0%) stddev-sans-first:24.90 09:38:31 INFO - PROCESS | 1671 | Values: 152.0 146.0 148.0 185.0 149.0 115.0 117.0 119.0 162.0 114.0 09:38:31 INFO - PROCESS | 1671 | 09:38:31 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:227.30 Median:232.50 stddev:33.23 (14.3%) stddev-sans-first:33.52 09:38:31 INFO - PROCESS | 1671 | Values: 198.0 201.0 207.0 269.0 284.0 219.0 196.0 198.0 255.0 246.0 09:38:31 INFO - PROCESS | 1671 | 09:38:31 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:75.30 Median:75.00 stddev:12.45 (16.6%) stddev-sans-first:13.20 09:38:31 INFO - PROCESS | 1671 | Values: 76.0 61.0 62.0 100.0 73.0 85.0 82.0 74.0 80.0 60.0 09:38:31 INFO - PROCESS | 1671 | 09:38:31 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:73.50 stddev:8.28 (11.3%) stddev-sans-first:7.88 09:38:31 INFO - PROCESS | 1671 | Values: 86.0 70.0 90.0 72.0 70.0 70.0 75.0 72.0 66.0 85.0 09:38:31 INFO - PROCESS | 1671 | 09:38:31 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:93.90 Median:90.50 stddev:11.71 (12.9%) stddev-sans-first:12.38 09:38:31 INFO - PROCESS | 1671 | Values: 91.0 89.0 91.0 90.0 127.0 88.0 91.0 89.0 93.0 90.0 09:38:31 INFO - PROCESS | 1671 | 09:38:31 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:126.40 Median:154.50 stddev:26.58 (17.2%) stddev-sans-first:25.97 09:38:31 INFO - PROCESS | 1671 | Values: 97.0 96.0 114.0 154.0 99.0 152.0 155.0 103.0 143.0 151.0 09:38:31 INFO - PROCESS | 1671 | 09:38:31 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:92.40 Median:88.50 stddev:6.43 (7.3%) stddev-sans-first:5.28 09:38:31 INFO - PROCESS | 1671 | Values: 104.0 97.0 87.0 87.0 102.0 87.0 90.0 93.0 89.0 88.0 09:38:31 INFO - PROCESS | 1671 | 09:38:31 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:58.60 Median:58.00 stddev:2.46 (4.2%) stddev-sans-first:2.60 09:38:31 INFO - PROCESS | 1671 | Values: 58.0 58.0 56.0 57.0 58.0 58.0 58.0 60.0 58.0 65.0 09:38:31 INFO - PROCESS | 1671 | 09:38:31 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:72.70 Median:73.50 stddev:7.62 (10.4%) stddev-sans-first:5.72 09:38:31 INFO - PROCESS | 1671 | Values: 88.0 74.0 70.0 81.0 73.0 68.0 70.0 64.0 76.0 63.0 09:38:31 INFO - PROCESS | 1671 | 09:38:31 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:95.00 Median:90.00 stddev:9.32 (10.4%) stddev-sans-first:9.86 09:38:31 INFO - PROCESS | 1671 | Values: 93.0 90.0 90.0 84.0 89.0 102.0 110.0 111.0 91.0 90.0 09:38:31 INFO - PROCESS | 1671 | 09:38:31 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:159.50 Median:162.00 stddev:14.18 (8.8%) stddev-sans-first:13.16 09:38:31 INFO - PROCESS | 1671 | Values: 140.0 154.0 160.0 147.0 164.0 169.0 168.0 151.0 190.0 152.0 09:38:31 INFO - PROCESS | 1671 | 09:38:31 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.70 Median:176.00 stddev:14.94 (8.5%) stddev-sans-first:1.66 09:38:31 INFO - PROCESS | 1671 | Values: 222.0 174.0 173.0 173.0 174.0 175.0 176.0 176.0 176.0 178.0 09:38:31 INFO - PROCESS | 1671 | 09:38:31 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:68.00 stddev:5.10 (7.5%) stddev-sans-first:5.37 09:38:31 INFO - PROCESS | 1671 | Values: 68.0 70.0 67.0 68.0 68.0 68.0 69.0 67.0 68.0 84.0 09:38:31 INFO - PROCESS | 1671 | -------- Summary: end -------- 09:38:31 INFO - PROCESS | 1671 | 09:38:31 INFO - TEST-INFO | 1671: exit 0 09:38:31 INFO - TEST-OK | kraken | took 60760ms 09:38:31 INFO - SUITE-END | took 1177s 09:38:32 INFO - Completed test suite (00:19:37) 09:38:32 INFO - javascript benchmark 09:38:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7061.671081858641, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3224.7903886247395, 3381.3913495570805, 3411.249525104477, 3416.043507817812, 3429.314137172566, 4851.1753001989655, 4932.950670493294, 5307.885344077748, 5684.516816070629, 5808.651480911149, 5689.4373967179445, 5707.286589176353, 5765.596408251424, 5782.335031471382, 5823.621464604797, 5235.921461178085, 5257.86855328616, 5271.472852714736, 5349.22436246744, 5387.676739395649, 5377.059014672436, 5387.973060134694, 5401.378841433227, 5416.539594134503, 5418.864528386802, 4748.596369308613, 4767.594754445876, 5334.226537152092, 5368.3021207243, 5411.702356370398, 5164.173732202829, 5186.844394668166, 5247.580193584504, 5251.0, 5278.366596008504, 5158.69047857126, 5161.071007218699, 5183.637145399824, 5184.8963020739375, 5231.346081739783, 5094.847154585368, 5154.814392689703, 5654.6324488908085, 5740.110282906115, 5751.482366587027, 5717.771289148429, 5755.4244575542525, 6156.199694039791, 6230.875382492325, 6273.0, 6260.906086408708, 6285.779997700104, 6301.054841773725, 6312.305646378894, 6330.8100756977365, 4616.8614941551805, 5424.538914192299, 5919.822405327847, 5931.199288096138, 5933.999999999957, 6145.262568491809, 6250.249970003629, 6272.090546870678, 6318.6524741138755, 6319.9368006320265, 6199.907001394983, 6240.500759939194, 6240.563160578716, 6245.313015568284, 6268.905966410507, 5159.071367153948, 5759.366469688331, 6300.7479700811455, 6314.589551679126, 6321.778737744157, 5641.928033673589, 6140.504248697391, 6161.691915404212, 6197.907031394533, 6224.502039836802, 6176.166217560662, 6244.2819075805955, 6287.591306565058, 6318.241810982711, 6334.651594162366, 4935.210366341368, 4981.352424184833, 5003.049420610072, 5004.474530174352, 5032.421271553818, 3757.530308711411, 4365.170617582649, 4415.050764085673, 4421.867343979686, 4480.775961201927, 5633.014222511044, 5781.82654520365, 5893.174955506232, 5922.763089476459, 5928.110783382527, 6253.812385628438, 6287.96856015717, 6300.21247344082, 6304.810855674337, 6317.747290108436, 5734.62724922887, 5766.163906233573, 5877.471027607536, 5888.823335299948, 5896.086106653475, 5382.058139825514, 5400.648957817729, 5420.593455490854, 5465.508104270555, 5505.284313039279, 6387.361263873576, 6395.20059992501, 6404.039394090923, 6422.133012043314, 6429.03564465334, 6146.385361463817, 6277.905831412624, 6323.177986861679, 6332.398422149889, 6335.65153916539, 6267.843303917256, 6270.122182894307, 6303.905441418474, 6308.52686048539, 6352.523560732871, 6243.282022567281, 6254.093156492192, 6264.436200741773, 6279.748810047547, 6282.371762823681, 6250.687465626609, 6260.786527471806, 6298.968505157628, 6305.211848518935, 6309.305976342691, 6046.697665116814, 6111.755529778759, 6115.449609535163, 6128.86980888409, 6140.478059364826, 6109.252844142575, 6136.877262454636, 6182.196314479089, 6196.535259855439, 6205.100260462117, 4656.627469802475, 5924.289085309791, 5962.850928726816, 5991.880162396815, 6090.147379366981, 5947.851303717442, 6106.915739268716, 6121.602095863755, 6136.243373036893, 6151.477124444473, 6037.849053773691, 6063.575549711478, 6090.116933044772, 6109.1010297800785, 6128.938710612837], "value": 5691.943231172903, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.7100197877316, 494.0464902737717, 498.0238732085113, 499.38824939449177, 553.070840987142, 38951.22097558047, 40387.39418908715, 40852.59147408525, 43344.78327608365, 43468.3479747804, 36694.26611467769, 39182.80408597953, 41602.37596436056, 42078.57921420785, 42300.57699423005, 8053.919460805376, 9418.717438476839, 9612.90387096127, 9620.374675646095, 9642.710718678416, 9430.764230894249, 9498.667546635868, 9520.761980950463, 9524.952375238114, 9570.760730981712, 9508.857367139464, 9562.0, 9562.235021198323, 9565.330426870121, 9567.0, 9446.527673616327, 9528.285378596598, 9536.380135291183, 9541.952290238574, 9553.996830332853, 7782.610869456561, 9263.6294548218, 9288.256939444827, 9338.439693618404, 9343.953280233623, 39703.80148099241, 40025.59974400277, 40040.3983840646, 40095.39856902149, 40098.39852402217, 3380.7633465657414, 3418.0942050356675, 3567.8046221087184, 3952.1898010907694, 3964.2467931093, 29276.41447171066, 30254.0, 30339.69660303413, 30564.0, 30626.84686576575, 3147.3075923297065, 3282.6224984126893, 3317.419451595961, 3332.783369081002, 3346.4143774839304, 376497.0, 440687.0, 443592.47426340246, 446507.0, 448300.0, 56086.15870761942, 56293.0, 56411.71794141003, 56511.43488565062, 56552.434475654714, 58970.70514647399, 59084.40915590789, 59390.0304984749, 59543.0, 59753.402465975654, 15465.613359666098, 15619.60950976212, 15696.76454853178, 15702.9214853925, 15721.764173537405, 10619.150467962545, 12182.147249692614, 12234.265944043385, 12269.631911042683, 12273.815892761615, 14348.06737562055, 14871.0, 14876.62808429798, 14891.478798242226, 15045.924770376078, 5674.319081710221, 6795.52431329812, 6983.336583024605, 7019.3682568569075, 7032.753853615099, 831.1812864328631, 851.595492141228, 955.7610597350663, 1078.5200585739428, 1094.3324572011068, 29982.25044373908, 30121.397572048438, 30609.693903060685, 30625.69374306273, 30847.064469685192, 1049.0872940541778, 1060.591672206206, 1089.5260561655705, 1199.6640940536663, 1237.7524495101015, 13024.869751302555, 13313.334333283297, 13520.918326533854, 15247.23763811805, 15466.922665386826, 588463.0, 656544.0, 657907.0, 661319.6934015299, 668892.0, 57992.8401431969, 58180.0, 58217.0, 58268.708656456445, 58324.292706339314, 1231.6859200903712, 1350.210127075652, 1384.6538365408649, 1406.4585134723204, 1408.2184387664945, 23734.0, 24549.509009819347, 28144.0, 29194.56208156922, 30562.388752224386, 47851.0, 48440.27339589979, 49350.27274045535, 49420.0, 49434.752826235635, 589679.0, 595312.0, 599138.0, 600704.0, 603341.0, 25980.480390392466, 26267.4746505065, 26291.21126366212, 26379.076732314275, 26409.73590264073, 39237.80381098076, 40413.989650258976, 40443.0, 40559.0, 40690.79654601708, 34730.652693473756, 37479.625203747615, 37839.62160378471, 39029.21941561096, 40500.0, 33677.0, 37519.43720844135, 37573.62426375701, 38631.798727416186, 38763.612363876004, 28683.28291792722, 32054.038378848672, 32757.836210818794, 36528.0, 37796.24407511779, 27134.907117677223, 30648.0, 31310.686893130776, 31935.68064319327, 34066.0, 22924.19765308206, 25550.0, 25642.384370813434, 25781.87109064443, 25862.74137258603], "value": 19253.059238665577, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.6007621654291, 367.36262584416033, 371.3538443108989, 375.2551185895994, 391.62795344422807, 19347.322843700473, 20029.098690558923, 20044.0, 20700.482487937792, 20826.375208743724, 11866.22869513481, 12042.81935770964, 12247.755044899097, 12331.568395106173, 12384.752304953896, 5229.8954020919555, 5581.274434323533, 5584.4136365681625, 5585.636933599303, 5591.6365436246715, 3897.4738410314526, 4130.5043394792665, 5387.757550910218, 5900.144479050535, 6152.3847615238565, 6189.102580125879, 6191.81424557264, 6201.193844800192, 6204.348543402945, 6215.192025036761, 6393.0, 6808.012838138442, 6813.693383797741, 6849.075374824385, 6851.691673874688, 5797.217375654275, 5954.315253745788, 5959.612625179349, 5990.101484777319, 6083.209182806251, 13276.46894124234, 13313.800292995613, 13395.598132056053, 13466.124701894443, 13523.797143042862, 2030.5735795482967, 2189.0477642225524, 2235.10595761695, 2238.0, 2945.896893608735, 9912.355696879751, 10043.096121349114, 10156.933521980234, 10350.689479315633, 10517.526711297933, 1790.355472030068, 1828.4606041217874, 1880.0693656639914, 1880.0975531744702, 1903.1816318982833, 111099.0, 115436.42281788537, 117401.0, 117475.0, 118106.22840657311, 11305.208635395607, 11472.369019703829, 11551.884481155248, 11567.363794991355, 11847.815218478083, 11773.587924422776, 12276.754464910831, 12925.54760583375, 13313.600591982256, 14484.493042743454, 8283.50298982063, 8384.622691978875, 8394.160583941679, 8414.537200453911, 8552.61513231906, 7325.816854578678, 8144.389170812213, 8241.50550966944, 8384.245417912296, 8385.45494542865, 3797.0, 3869.7423337415453, 4553.635709143261, 4562.543745625478, 4655.767211639472, 1608.8898659924614, 1657.544175351764, 1694.7118989771768, 1905.59982403694, 1970.5073731567109, 863.9438436501607, 914.7758799094264, 934.3319526538558, 992.806402751471, 1027.994860025695, 18165.909170454062, 20636.277730279668, 20975.475613109793, 22082.116715331205, 22410.77589224087, 851.9957400212959, 908.795521007768, 942.2320808541155, 993.9503024848729, 996.5400687992127, 10387.013233742782, 11051.889481105087, 11293.943530282297, 11571.611406631258, 11711.01369150878, 358894.0, 554386.0, 568270.0, 585872.0, 586219.0, 131562.34218829035, 133256.0, 133540.0, 133853.0, 136424.0, 943.7782121201512, 956.6125719083782, 971.8299297622888, 980.6077568972468, 981.1856159387692, 19264.711029334852, 19485.830850149036, 19487.415377538695, 19509.60980780348, 19551.826890386623, 51056.48943510517, 51942.48057519377, 52717.0, 53483.732581336844, 53756.46243537514, 73297.26702732904, 76800.0, 77321.61339193268, 77992.0, 78289.21710783047, 6993.370596646327, 7352.632368381666, 7405.740799072007, 7546.24537546241, 7675.309222170032, 7928.960355198187, 13013.024023198488, 13018.28399438002, 13469.663258418617, 13684.110532815335, 11368.928061076398, 11409.714757131138, 11416.086713063101, 11661.008814250883, 11742.471588778355, 10897.618583349546, 11267.943660281646, 11554.47415525327, 11726.70683232926, 11762.70593235176, 11363.602273920373, 11435.828462573234, 11456.82814757796, 11521.366324852213, 11957.94021029924, 7444.255574442729, 7503.174650788518, 7603.9619801900635, 7637.312641862259, 7639.923600763921, 9274.44353338802, 11678.766424671629, 11748.177627565989, 11814.173007889365, 11820.995215406787], "value": 9173.638081215668, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1426.5934208750505, 1534.278888922206, 1764.691179043667, 1887.820657037581, 1911.7514723085997, 7457.62711864406, 7685.615719214031, 8017.278444939961, 8507.957460212698, 8509.53197574134, 7937.325327347168, 8234.135415781331, 8263.752087437371, 8333.166683331663, 8339.583020848964, 2255.064148378425, 2272.7045484087043, 2279.2706333973133, 2288.141946769961, 2289.027163455533, 2266.841321107515, 2297.3452566018686, 2327.2552783109413, 2328.988355058222, 2331.5220379822094, 2261.072960086365, 2277.1574517428494, 2285.451491642002, 2291.140822191678, 2294.7705229477087, 2230.1413955627118, 2246.3036458697857, 2261.796438320551, 2263.4228271790753, 2275.1354485295565, 1957.618264438435, 2265.32040387883, 2289.8053665438456, 2297.5634629420356, 2312.2022902098747, 6592.604443733391, 7240.384567311785, 7617.46677732559, 8375.664973401057, 8384.622691978875, 5013.949860501385, 5185.274061631375, 5188.9881473112755, 5201.063808514466, 5233.371995360543, 3176.253580408625, 3262.5432439458496, 3510.701590364822, 3511.315293517765, 3526.1537231064617, 1020.9489525523694, 1039.2880876599554, 1046.9581216751321, 1060.199549340251, 1063.8936106389376, 10553.52509137083, 10565.63020294286, 10608.62869799561, 10740.08709259714, 10756.0, 4387.188370151533, 4423.955760442354, 4634.907301854011, 4636.142313671981, 4644.210484217691, 8741.956290218508, 9223.354365194373, 9296.907030929604, 9309.767255818524, 9312.674056408128, 5161.664491808096, 5166.147585648326, 5169.844904652867, 5170.663906846043, 5185.818496352609, 3591.964080359215, 4482.641388688897, 4676.836310729108, 4748.169070412664, 4754.429468463806, 3695.113172838553, 3711.294853977736, 3764.0966168119453, 3798.6581207691443, 3815.275097731422, 1220.8107743299802, 1240.274439452912, 1245.6574442028334, 1248.7377650693456, 1251.249250449741, 905.2079430498314, 919.9448033118034, 924.5562130177552, 945.4658118163215, 954.6801821389773, 5595.38450770415, 5737.311522617312, 5852.297724273115, 5854.360778981807, 5873.706314684248, 733.7541923455595, 864.6454953469048, 886.9932626469005, 919.2783664823082, 953.4565297780199, 3297.9446577095578, 3555.0, 3657.00693122249, 3849.9230015400094, 3937.838337690388, 9330.393524420891, 9959.950200248952, 10008.249381296431, 10022.799544009078, 10032.899671003197, 7418.591977441185, 8930.732078037669, 9898.802023959624, 10005.399676019462, 10021.549030293654, 6519.8696026079215, 6705.396514313735, 6798.7280508779095, 7902.881456778153, 7970.402219833536, 5529.115341545334, 5538.307711536058, 5573.052581061148, 5632.831015069554, 5689.516391106802, 7788.026496687914, 9246.306527010367, 9402.15380615748, 9424.29317801154, 9447.385919915183, 9168.54157292146, 9382.953085234803, 9399.0, 9470.95264523673, 9578.425294482353, 6260.40526150015, 6445.0, 6556.475481961336, 6752.561083529555, 6790.558613690095, 6720.899186512109, 7730.729424470116, 7999.000124984377, 8009.759707208793, 8013.639386227517, 6598.505112116514, 6779.661016949231, 7045.894311585433, 7161.104861892263, 7273.927260727537, 7031.452561877987, 7032.050673159058, 7054.435645148274, 7055.223925368308, 7137.607431591312, 6594.241662208908, 6629.303923088007, 6697.598144111369, 6712.463002959849, 6714.2278637955305, 4774.522547745198, 4823.252395878644, 4836.830710925101, 4870.0, 4925.532074452921, 4121.381792731115, 4742.193827049479, 4799.952000479951, 4934.407871055496, 5014.398272207359], "value": 4344.559830872613, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.0930510764076, 292.8140630699506, 301.4393228594814, 306.119905272342, 309.2762934732725, 4338.8915277118085, 5275.683458992463, 5459.69971651559, 5466.480684334992, 5491.615586908913, 4363.585459381354, 4909.797099710576, 5441.673499590027, 5454.045542030149, 5479.424660410659, 4161.708680392374, 4170.603792639709, 4171.770552619605, 4173.332266837307, 4190.413342132104, 3611.5305010348748, 4115.485564304462, 4185.91628167438, 4201.852935147271, 4215.873523794291, 3412.4198886189406, 3524.0926235855395, 3807.4859893914245, 3859.807009649506, 3931.2309460742636, 4096.955276404513, 4113.568075352075, 4129.0, 4131.876043718678, 4132.912878996088, 3553.6446355364255, 3554.680078792895, 3579.838907249154, 3584.5160903278247, 3608.2422691234874, 4518.289026584056, 4781.760911954389, 5120.874836942664, 5337.332833395825, 5357.19642053695, 4629.583337499607, 4656.837010704643, 4663.44038715353, 4673.789679464398, 4688.367070445481, 4510.323451482271, 4736.3369128322065, 5797.507211886952, 5815.79644712433, 5845.181674565564, 2999.505081661545, 3432.8843125984154, 3649.288388764192, 3818.522684664417, 3916.138449541096, 5092.337996060489, 5152.098382783035, 5689.6017278790505, 6183.845403864894, 6200.87598248037, 3640.5995340512522, 3794.582595914447, 3832.827522761482, 3972.225416043844, 3975.2906250312644, 3438.157651375429, 3890.2997460456822, 4023.7183397161916, 4030.4760381150263, 4040.8685568040996, 4478.395416618715, 4626.2705004047775, 4641.744704041243, 4683.7892294846815, 4700.45944716362, 4751.7882939850115, 5840.707964601753, 5867.157012157044, 5879.588428810028, 5915.911261331084, 960.8798900137483, 1308.8036794480904, 1324.966875828112, 1347.622665653599, 1358.6875018745743, 877.3025444771422, 892.9642814287356, 901.4275934781462, 913.1370854542394, 914.8719179314901, 4640.95578494836, 4769.833055843029, 4786.617070634411, 5153.072446959508, 5404.594805350637, 5063.594912406998, 5815.156802263648, 5923.0, 5976.760929562855, 6037.969810151009, 4324.459442569679, 5254.369475662945, 5421.241026256327, 5446.759178288912, 5468.562514998791, 2311.9653205201935, 2405.5102968427595, 2564.1153801938303, 2624.2604288888256, 2633.289011966758, 4295.1437294199, 4774.7135171889795, 4806.077568972369, 5094.923576146361, 5172.456892026283, 4040.5151381834366, 4064.309067458539, 4499.0, 5041.663959050855, 5065.316182315414, 4457.866264012085, 4680.455449701591, 4728.976355118202, 5105.166632518987, 5158.561522270499, 4928.67963582366, 4930.8767280818265, 4932.753362331798, 5006.524380183877, 5037.614655969491, 3934.7639141651593, 4442.4669039714145, 4512.571305725951, 4574.748388838512, 4920.630952678635, 4313.288307429365, 4333.544977777288, 5041.6722913010535, 5080.282049103859, 5097.643164978416, 3012.328157872402, 3423.134391756449, 3431.5024321473747, 3436.8281585921104, 3470.015786423724, 4251.489821221473, 4605.30920361935, 4825.686330388514, 4985.651004429655, 5251.6323857331145, 4592.563706447882, 5753.021986262262, 5758.395368486249, 5763.538916886555, 5801.50535269838, 5682.602217844711, 5712.714364281853, 5803.9419605803405, 5809.8503553685405, 5842.912356314574, 4678.321643361762, 5609.49514543693, 5669.85825354353, 5712.715188766393, 5799.217105690678, 5870.090136028923, 5901.675407852609, 5907.586468947133, 5946.524278057658, 5951.404859514014, 5453.7818487260065, 5773.91339129905, 5809.331926828299, 5812.796552120655, 5843.766249349978], "value": 3961.0797674295914, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.871143194225, 743.0748717846283, 760.9733659321925, 768.4313607930134, 839.576014112873, 14477.05899116558, 15175.696486070272, 15191.848081519209, 15245.92377038116, 15247.0851748895, 13891.374888130033, 14018.859811401882, 14048.508302209424, 14060.296985150753, 14098.64753381166, 681.7239018197638, 701.9438444924419, 845.8984921809391, 864.7578677970179, 875.234170101163, 797.6769408389582, 890.3055616619048, 891.8974317953454, 896.2874514760748, 896.7847716548047, 808.7129069180415, 941.722191953372, 942.3591957468918, 942.4392486470573, 943.6602822983721, 901.5897766516252, 928.401181238101, 934.6168071090854, 935.4293880732747, 938.2869019545127, 690.492488021304, 697.7174244431013, 698.179638924267, 698.3470455124475, 698.5250029979612, 14452.927735361362, 14455.060421072596, 14580.854191458056, 14662.193579353132, 15078.924605377013, 14068.859311406857, 14170.433182672681, 14259.358328875225, 14260.768916073868, 14268.714625707427, 11244.550217991271, 11431.771364572662, 11445.370504622242, 11462.0, 11616.477258523386, 3723.1995121048835, 3724.2923844469733, 3842.53889533255, 3881.7670939743425, 3924.0, 63268.41828954313, 88557.55721221417, 89356.55321723415, 89368.0, 96102.55846162314, 10866.782664346669, 10925.781484370267, 10928.125749939985, 11005.669829905115, 11285.774284514428, 21950.231741889278, 22377.440563985703, 22408.103675853108, 22425.0, 22448.32655020352, 7622.695092196361, 7626.084869815547, 7655.349295309849, 7947.721829735931, 7997.6800927963395, 3654.744167908221, 4133.318002529609, 4193.433886425354, 4257.106007738348, 4311.8490852820005, 3447.1899103710607, 3522.947155792665, 3529.982350088233, 3564.2515071834687, 3577.069961809948, 634.3656343656344, 662.1491383572087, 663.5985228936424, 674.9730010799611, 683.8256244657581, 526.210683974039, 577.2149876168407, 599.2958274028, 631.9399657032575, 651.2835880531378, 8487.575621219037, 8787.824243515093, 9200.125988031126, 9271.490068046187, 9444.0, 623.7006237006262, 655.9868802623921, 657.3853447027045, 668.7859884836805, 673.1686367336335, 4335.458067741532, 4496.0, 4567.520410356865, 4625.167469855391, 4629.579079543604, 47543.04913901703, 66566.33433665601, 108438.0, 109706.0, 112227.0, 60657.09014364788, 88056.0, 102478.0, 103242.48378758058, 105185.0, 51267.743661281456, 59488.30701579018, 60502.0, 71865.92201116988, 80153.0, 8299.792505187419, 8435.873461897954, 9582.904170958202, 9891.604335826774, 9911.405315681082, 51943.74028129835, 53165.73417133044, 54026.656841707205, 55370.72314638401, 56391.308260752245, 57642.0, 62162.37837621747, 77963.0, 78633.60683196547, 79851.0, 11014.614488492864, 11056.226064175431, 11059.944700276446, 11204.551817927191, 11452.770944581229, 11967.940160299491, 12042.698932526757, 12060.51757929673, 12110.939445302716, 12451.128421010442, 11373.33207810815, 11646.88353116458, 11710.238834475731, 11710.355930423568, 12021.459034343301, 11676.591319304123, 11712.472938717608, 11760.235584686969, 11767.52929882795, 12036.518539258683, 11381.317120972768, 11397.886021139682, 11687.123465739933, 11849.940750296539, 12010.939945300217, 9697.854532182164, 9841.753956150867, 9959.0, 10029.69910902645, 10181.083702466518, 9477.620895164117, 10022.148117410103, 10030.147437467605, 10048.246381521269, 10054.24593155531], "value": 7166.980901395124, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1583.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 121.0, 118.0, 116.0, 118.0, 118.0, 119.0, 118.0, 107.0, 118.0], "value": 116.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 146.0, 148.0, 185.0, 149.0, 115.0, 117.0, 119.0, 162.0, 114.0], "value": 140.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 201.0, 207.0, 269.0, 284.0, 219.0, 196.0, 198.0, 255.0, 246.0], "value": 227.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 61.0, 62.0, 100.0, 73.0, 85.0, 82.0, 74.0, 80.0, 60.0], "value": 75.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 90.0, 72.0, 70.0, 70.0, 75.0, 72.0, 66.0, 85.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 91.0, 90.0, 127.0, 88.0, 91.0, 89.0, 93.0, 90.0], "value": 93.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 114.0, 154.0, 99.0, 152.0, 155.0, 103.0, 143.0, 151.0], "value": 126.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 97.0, 87.0, 87.0, 102.0, 87.0, 90.0, 93.0, 89.0, 88.0], "value": 92.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 56.0, 57.0, 58.0, 58.0, 58.0, 60.0, 58.0, 65.0], "value": 58.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 74.0, 70.0, 81.0, 73.0, 68.0, 70.0, 64.0, 76.0, 63.0], "value": 72.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 90.0, 90.0, 84.0, 89.0, 102.0, 110.0, 111.0, 91.0, 90.0], "value": 95.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 154.0, 160.0, 147.0, 164.0, 169.0, 168.0, 151.0, 190.0, 152.0], "value": 159.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [222.0, 174.0, 173.0, 173.0, 174.0, 175.0, 176.0, 176.0, 176.0, 178.0], "value": 179.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 67.0, 68.0, 68.0, 68.0, 69.0, 67.0, 68.0, 84.0], "value": 69.7, "unit": "score"}]}]} 09:38:32 INFO - Return code: 0 09:38:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:38:32 ERROR - # TBPL SUCCESS # 09:38:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:32 INFO - 2016-10-29 09:38:32.326 screenresolution[1676:10733] starting screenresolution argv=screenresolution get 09:38:32 INFO - 2016-10-29 09:38:32.328 screenresolution[1676:10733] Display 0: 1600x1200x32@60 09:38:32 INFO - 2016-10-29 09:38:32.334 screenresolution[1677:10736] starting screenresolution argv=screenresolution list 09:38:32 INFO - Available Modes on Display 0 09:38:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:38:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:38:32 INFO - Intel Iris: 09:38:32 INFO - Chipset Model: Intel Iris 09:38:32 INFO - Type: GPU 09:38:32 INFO - Bus: Built-In 09:38:32 INFO - VRAM (Dynamic, Max): 1536 MB 09:38:32 INFO - Vendor: Intel (0x8086) 09:38:32 INFO - Device ID: 0x0a2e 09:38:32 INFO - Revision ID: 0x0009 09:38:32 INFO - Displays: 09:38:32 INFO - Display: 09:38:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:38:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:38:32 INFO - Display Serial Number: 1600x1200 60 09:38:32 INFO - Main Display: Yes 09:38:32 INFO - Mirror: Off 09:38:32 INFO - Online: Yes 09:38:32 INFO - Rotation: Supported 09:38:32 INFO - Return code: 0 09:38:32 INFO - Running post-action listener: _resource_record_post_action 09:38:32 INFO - [mozharness: 2016-10-29 16:38:32.570495Z] Finished run-tests step (success) 09:38:32 INFO - Running post-run listener: _resource_record_post_run 09:38:32 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 38533632; Write bytes: 711205888; Read time: 246; Write time: 1995 09:38:32 INFO - TinderboxPrint: CPU usage
54.7% 09:38:32 INFO - TinderboxPrint: I/O read bytes / time
38,533,632 / 246 09:38:32 INFO - TinderboxPrint: I/O write bytes / time
711,205,888 / 1,995 09:38:32 INFO - TinderboxPrint: CPU idle
2,201.1 (45.3%) 09:38:32 INFO - TinderboxPrint: CPU system
983.0 (20.3%) 09:38:32 INFO - TinderboxPrint: CPU user
1,669.7 (34.4%) 09:38:32 INFO - TinderboxPrint: Swap in / out
445,480,960 / 0 09:38:32 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 407872000; Write bytes: 410052608; Read time: 30908; Write time: 842 09:38:32 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 35482112; Write bytes: 299576320; Read time: 223; Write time: 1146 09:38:33 INFO - Running post-run listener: _upload_blobber_files 09:38:33 INFO - Blob upload gear active. 09:38:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:38:33 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 09:38:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:38:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:38:33 INFO - (blobuploader) - INFO - Open directory for files ... 09:38:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:38:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:38:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:34 INFO - (blobuploader) - INFO - Done attempting. 09:38:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:38:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:38:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:35 INFO - (blobuploader) - INFO - Done attempting. 09:38:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:38:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:38:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:37 INFO - (blobuploader) - INFO - Done attempting. 09:38:37 INFO - (blobuploader) - INFO - Iteration through files over. 09:38:37 INFO - Return code: 0 09:38:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:38:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:38:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fd3201c699f85401f71dcce1597d065ae6a9c73712cfe84ff14522097fad5f3b22cd2a705d5385eb6c6a0c101d206d6c6c4226c1dfe860332efbb53808f06c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6dedc367359ae61e89653094d944cd3f10f6e87ca1aa5492e5bcbde33e27eef8fba2ef372a88e4d4ec69f0f3b05c36f45a0d11919e02a97187c8a5db238eb9a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05db8ccc7fb9d3b81645cfcbbe42ee95f7fd8674d8fcd35a66622943226ab4b7912d7bf2f22dbcbe9eafd57b089b2bb7750f95c263c90b2329db95a749684e23"} 09:38:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:38:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:38:37 INFO - Contents: 09:38:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fd3201c699f85401f71dcce1597d065ae6a9c73712cfe84ff14522097fad5f3b22cd2a705d5385eb6c6a0c101d206d6c6c4226c1dfe860332efbb53808f06c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6dedc367359ae61e89653094d944cd3f10f6e87ca1aa5492e5bcbde33e27eef8fba2ef372a88e4d4ec69f0f3b05c36f45a0d11919e02a97187c8a5db238eb9a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05db8ccc7fb9d3b81645cfcbbe42ee95f7fd8674d8fcd35a66622943226ab4b7912d7bf2f22dbcbe9eafd57b089b2bb7750f95c263c90b2329db95a749684e23"} 09:38:37 INFO - Running post-run listener: copy_logs_to_upload_dir 09:38:37 INFO - Copying logs to upload dir... 09:38:37 INFO - mkdir: /builds/slave/test/build/upload/logs 09:38:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.992415 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-29 09:38:37.812754) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 09:38:37.815978) ========= 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.39bIGHaPDr/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.9OVJ0Xm7qS/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fd3201c699f85401f71dcce1597d065ae6a9c73712cfe84ff14522097fad5f3b22cd2a705d5385eb6c6a0c101d206d6c6c4226c1dfe860332efbb53808f06c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6dedc367359ae61e89653094d944cd3f10f6e87ca1aa5492e5bcbde33e27eef8fba2ef372a88e4d4ec69f0f3b05c36f45a0d11919e02a97187c8a5db238eb9a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05db8ccc7fb9d3b81645cfcbbe42ee95f7fd8674d8fcd35a66622943226ab4b7912d7bf2f22dbcbe9eafd57b089b2bb7750f95c263c90b2329db95a749684e23"} build_url:https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010900 build_url: 'https://queue.taskcluster.net/v1/task/RhrWpSubQsS1SHtTePwsdw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fd3201c699f85401f71dcce1597d065ae6a9c73712cfe84ff14522097fad5f3b22cd2a705d5385eb6c6a0c101d206d6c6c4226c1dfe860332efbb53808f06c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6dedc367359ae61e89653094d944cd3f10f6e87ca1aa5492e5bcbde33e27eef8fba2ef372a88e4d4ec69f0f3b05c36f45a0d11919e02a97187c8a5db238eb9a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05db8ccc7fb9d3b81645cfcbbe42ee95f7fd8674d8fcd35a66622943226ab4b7912d7bf2f22dbcbe9eafd57b089b2bb7750f95c263c90b2329db95a749684e23"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 09:38:37.849819) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 09:38:37.850170) ========= 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.39bIGHaPDr/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.9OVJ0Xm7qS/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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.004985 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 09:38:37.899623) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-29 09:38:37.899957) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-29 09:38:37.900410) ========= ========= Total master_lag: 0.18 =========