builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0310 starttime: 1477631787.83 results: success (0) buildid: 20161027192219 builduid: c193b4f3423945a1a7795dfefb47f0a0 revision: 944cb0fd05526894fcd90fbe7d1e625ee53cd73d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-27 22:16:27.828903) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-27 22:16:27.829303) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-27 22:16:27.829582) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-27 22:16:27.852250) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 22:16:27.852518) ========= 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.w5EbPZNsH3/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.o3Jtyjr5nh/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-27 22:16:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.97M=0.001s 2016-10-27 22:16:28 (7.97 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.357128 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 22:16:28.231143) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 22:16:28.231456) ========= 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.w5EbPZNsH3/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.o3Jtyjr5nh/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.019711 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 22:16:28.283483) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-27 22:16:28.286845) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 944cb0fd05526894fcd90fbe7d1e625ee53cd73d --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 944cb0fd05526894fcd90fbe7d1e625ee53cd73d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w5EbPZNsH3/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.o3Jtyjr5nh/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-27 22:16:28,363 truncating revision to first 12 chars 2016-10-27 22:16:28,363 Setting DEBUG logging. 2016-10-27 22:16:28,363 attempt 1/10 2016-10-27 22:16:28,363 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/944cb0fd0552?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-27 22:16:29,184 unpacking tar archive at: mozilla-central-944cb0fd0552/testing/mozharness/ program finished with exit code 0 elapsedTime=1.071592 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-27 22:16:29.380289) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-27 22:16:29.380606) ========= script_repo_revision: 944cb0fd05526894fcd90fbe7d1e625ee53cd73d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-27 22:16:29.384034) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-27 22:16:29.384312) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-27 22:16:29.402795) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-27 22:16:29.403096) ========= /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.w5EbPZNsH3/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.o3Jtyjr5nh/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 22:16:29 INFO - MultiFileLogger online at 20161027 22:16:29 in /builds/slave/test 22:16:29 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 22:16:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:16:29 INFO - {'append_to_log': False, 22:16:29 INFO - 'base_work_dir': '/builds/slave/test', 22:16:29 INFO - 'blob_upload_branch': 'Firefox', 22:16:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:16:29 INFO - 'branch': 'Firefox', 22:16:29 INFO - 'buildbot_json_path': 'buildprops.json', 22:16:29 INFO - 'config_files': ('talos/mac_config.py',), 22:16:29 INFO - 'default_actions': ('clobber', 22:16:29 INFO - 'read-buildbot-config', 22:16:29 INFO - 'download-and-extract', 22:16:29 INFO - 'populate-webroot', 22:16:29 INFO - 'create-virtualenv', 22:16:29 INFO - 'install', 22:16:29 INFO - 'run-tests'), 22:16:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:16:29 INFO - 'download_minidump_stackwalk': True, 22:16:29 INFO - 'download_symbols': 'ondemand', 22:16:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:16:29 INFO - 'tooltool.py': '/tools/tooltool.py', 22:16:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:16:29 INFO - '/tools/misc-python/virtualenv.py')}, 22:16:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:16:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:16:29 INFO - 'installer_path': 'installer.exe', 22:16:29 INFO - 'log_level': 'info', 22:16:29 INFO - 'log_name': 'talos', 22:16:29 INFO - 'log_to_console': True, 22:16:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:16:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:16:29 INFO - 'opt_config_files': (), 22:16:29 INFO - 'pip_index': False, 22:16:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:16:29 INFO - 'cmd': ('bash', 22:16:29 INFO - '-c', 22:16:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:16:29 INFO - 'enabled': True, 22:16:29 INFO - 'halt_on_failure': False, 22:16:29 INFO - 'name': 'check_screen_resolution'},), 22:16:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:16:29 INFO - 'cmd': ('bash', 22:16:29 INFO - '-c', 22:16:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:16:29 INFO - 'enabled': True, 22:16:29 INFO - 'halt_on_failure': False, 22:16:29 INFO - 'name': 'check_screen_resolution'},), 22:16:29 INFO - 'run_cmd_checks_enabled': True, 22:16:29 INFO - 'sps_profile': False, 22:16:29 INFO - 'sps_profile_interval': 0, 22:16:29 INFO - 'suite': 'dromaeojs-e10s', 22:16:29 INFO - 'system_bits': '32', 22:16:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:16:29 INFO - 'title': 't-yosemite-r7-0310', 22:16:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:16:29 INFO - 'use_talos_json': True, 22:16:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:16:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:16:29 INFO - 'work_dir': 'build'} 22:16:29 INFO - [mozharness: 2016-10-28 05:16:29.597276Z] Running clobber step. 22:16:29 INFO - Running pre-action listener: _resource_record_pre_action 22:16:29 INFO - Running main action method: clobber 22:16:29 INFO - rmtree: /builds/slave/test/build 22:16:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:16:31 INFO - Running post-action listener: _resource_record_post_action 22:16:31 INFO - [mozharness: 2016-10-28 05:16:31.121562Z] Finished clobber step (success) 22:16:31 INFO - [mozharness: 2016-10-28 05:16:31.121687Z] Running read-buildbot-config step. 22:16:31 INFO - Running pre-action listener: _resource_record_pre_action 22:16:31 INFO - Running main action method: read_buildbot_config 22:16:31 INFO - Using buildbot properties: 22:16:31 INFO - { 22:16:31 INFO - "project": "", 22:16:31 INFO - "product": "firefox", 22:16:31 INFO - "script_repo_revision": "production", 22:16:31 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 22:16:31 INFO - "repository": "", 22:16:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 22:16:31 INFO - "buildid": "20161027192219", 22:16:31 INFO - "pgo_build": "False", 22:16:31 INFO - "basedir": "/builds/slave/test", 22:16:31 INFO - "buildnumber": 154, 22:16:31 INFO - "slavename": "t-yosemite-r7-0310", 22:16:31 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 22:16:31 INFO - "platform": "yosemite_r7", 22:16:31 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:16:31 INFO - "branch": "mozilla-central", 22:16:31 INFO - "repo_path": "mozilla-central", 22:16:31 INFO - "revision": "944cb0fd05526894fcd90fbe7d1e625ee53cd73d", 22:16:31 INFO - "stage_platform": "macosx64", 22:16:31 INFO - "builduid": "c193b4f3423945a1a7795dfefb47f0a0", 22:16:31 INFO - "slavebuilddir": "test" 22:16:31 INFO - } 22:16:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 22:16:31 INFO - Running post-action listener: _resource_record_post_action 22:16:31 INFO - [mozharness: 2016-10-28 05:16:31.124996Z] Finished read-buildbot-config step (success) 22:16:31 INFO - [mozharness: 2016-10-28 05:16:31.125126Z] Running download-and-extract step. 22:16:31 INFO - Running pre-action listener: _resource_record_pre_action 22:16:31 INFO - Running main action method: download_and_extract 22:16:31 INFO - mkdir: /builds/slave/test/build/tests 22:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:31 INFO - trying https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 22:16:31 INFO - Downloading https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/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 22:16:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/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 22:16:31 INFO - Downloaded 1199 bytes. 22:16:31 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 22:16:31 INFO - Using the following test package requirements: 22:16:31 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:16:31 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:16:31 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 22:16:31 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:16:31 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 22:16:31 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:16:31 INFO - u'jsshell-mac.zip'], 22:16:31 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:16:31 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 22:16:31 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:16:31 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:16:31 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 22:16:31 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:16:31 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 22:16:31 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:16:31 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 22:16:31 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:16:31 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 22:16:31 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:16:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:16:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:16:31 INFO - Fetch https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:16:32 INFO - Http code: 200 22:16:32 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 22:16:32 INFO - x-amz-cf-id: pWW678c9xOU60Y_KHesMAJX7xH1c-cMKYMgVF4u2iVMTcBkj0Bj4-g== 22:16:32 INFO - x-amz-version-id: elUrSEMT5OakK3pmyMJYdbfc5njjaIaT 22:16:32 INFO - Expected file size: 16377319 22:16:32 INFO - Obtained file size: 16377319 22:16:39 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 22:16:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:16:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:16:39 INFO - Fetch https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:16:40 INFO - Http code: 200 22:16:40 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 22:16:40 INFO - x-amz-cf-id: PxrTBR-W8YxYqGz4NSE-lGIOgnlocjDUMLP5jJeax7p2ujAeJZ7w2A== 22:16:40 INFO - x-amz-version-id: elUrSEMT5OakK3pmyMJYdbfc5njjaIaT 22:16:40 INFO - Expected file size: 16377319 22:16:40 INFO - Obtained file size: 16377319 22:16:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 22:16:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:16:46 INFO - Fetch https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 22:16:47 INFO - Http code: 200 22:16:47 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 22:16:47 INFO - x-amz-cf-id: U570mvKWMTbLperTnE8HC-YHa3h_m4m8wpA--29XAQMdBqRE6i6Dwg== 22:16:47 INFO - x-amz-version-id: wY.p4qsjMSIPxjDfLF_w3CR.97q9VQFE 22:16:48 INFO - Expected file size: 14409028 22:16:48 INFO - Obtained file size: 14409028 22:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:48 INFO - trying https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:16:48 INFO - Downloading https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 22:16:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/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 22:16:51 INFO - Downloaded 111544952 bytes. 22:16:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:16:51 INFO - mkdir: /builds/slave/test/properties 22:16:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:16:51 INFO - Writing to file /builds/slave/test/properties/build_url 22:16:51 INFO - Contents: 22:16:51 INFO - build_url:https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:16:52 INFO - Running post-action listener: _resource_record_post_action 22:16:52 INFO - Running post-action listener: set_extra_try_arguments 22:16:52 INFO - [mozharness: 2016-10-28 05:16:52.256726Z] Finished download-and-extract step (success) 22:16:52 INFO - [mozharness: 2016-10-28 05:16:52.256884Z] Running populate-webroot step. 22:16:52 INFO - Running pre-action listener: _resource_record_pre_action 22:16:52 INFO - Running main action method: populate_webroot 22:16:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:16:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:16:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:16:52 INFO - '--tppagecycles', 22:16:52 INFO - '7'], 22:16:52 INFO - 'tests': ['tsvgm']}}, 22:16:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:16:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:16:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:16:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:16:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:16:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:16:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:16:52 INFO - 'talos_options': ['--disable-e10s'], 22:16:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:16:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:16:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:16:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:16:52 INFO - 'talos_options': ['--disable-e10s'], 22:16:52 INFO - 'tests': ['damp', 'tps']}, 22:16:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:16:52 INFO - 'tests': ['damp', 'tps']}, 22:16:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:16:52 INFO - 'tests': ['dromaeo_dom']}, 22:16:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:16:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:16:52 INFO - 'tests': ['basic_compositor_video']}, 22:16:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:16:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:16:52 INFO - 'tests': ['a11yr', 22:16:52 INFO - 'ts_paint', 22:16:52 INFO - 'tpaint', 22:16:52 INFO - 'sessionrestore', 22:16:52 INFO - 'sessionrestore_no_auto_restore', 22:16:52 INFO - 'tabpaint']}, 22:16:52 INFO - 'other-e10s': {'tests': ['a11yr', 22:16:52 INFO - 'ts_paint', 22:16:52 INFO - 'tpaint', 22:16:52 INFO - 'sessionrestore', 22:16:52 INFO - 'sessionrestore_no_auto_restore', 22:16:52 INFO - 'tabpaint']}, 22:16:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:16:52 INFO - 'ts_paint', 22:16:52 INFO - 'tpaint', 22:16:52 INFO - 'sessionrestore', 22:16:52 INFO - 'sessionrestore_no_auto_restore', 22:16:52 INFO - 'tabpaint']}, 22:16:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:16:52 INFO - 'ts_paint', 22:16:52 INFO - 'tpaint', 22:16:52 INFO - 'sessionrestore', 22:16:52 INFO - 'sessionrestore_no_auto_restore', 22:16:52 INFO - 'tabpaint']}, 22:16:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:16:52 INFO - 'tests': ['a11yr', 22:16:52 INFO - 'ts_paint', 22:16:52 INFO - 'tpaint', 22:16:52 INFO - 'sessionrestore', 22:16:52 INFO - 'sessionrestore_no_auto_restore', 22:16:52 INFO - 'tabpaint']}, 22:16:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:16:52 INFO - 'tests': ['a11yr', 22:16:52 INFO - 'ts_paint', 22:16:52 INFO - 'tpaint', 22:16:52 INFO - 'sessionrestore', 22:16:52 INFO - 'sessionrestore_no_auto_restore', 22:16:52 INFO - 'tabpaint']}, 22:16:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:16:52 INFO - 'tests': ['tsvgx', 22:16:52 INFO - 'tsvgr_opacity', 22:16:52 INFO - 'tart', 22:16:52 INFO - 'tscrollx', 22:16:52 INFO - 'cart']}, 22:16:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:16:52 INFO - 'tsvgr_opacity', 22:16:52 INFO - 'tart', 22:16:52 INFO - 'tscrollx', 22:16:52 INFO - 'cart']}, 22:16:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:16:52 INFO - 'talos_options': ['--disable-e10s'], 22:16:52 INFO - 'tests': ['tp5o']}, 22:16:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:16:52 INFO - 'tests': ['tp5o']}, 22:16:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:16:52 INFO - 'talos_options': ['--disable-e10s', 22:16:52 INFO - '--xperf_path', 22:16:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:16:52 INFO - 'tests': ['tp5n']}, 22:16:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:16:52 INFO - 'talos_options': ['--xperf_path', 22:16:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:16:52 INFO - 'tests': ['tp5n']}}, 22:16:52 INFO - 'talos.zip': {'path': '', 22:16:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:16:52 INFO - Running post-action listener: _resource_record_post_action 22:16:52 INFO - [mozharness: 2016-10-28 05:16:52.276555Z] Finished populate-webroot step (success) 22:16:52 INFO - [mozharness: 2016-10-28 05:16:52.276644Z] Running create-virtualenv step. 22:16:52 INFO - Running pre-action listener: _resource_record_pre_action 22:16:52 INFO - Running main action method: create_virtualenv 22:16:52 INFO - Creating virtualenv /builds/slave/test/build/venv 22:16:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:16:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:16:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:16:52 INFO - Using real prefix '/tools/python27' 22:16:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:16:53 INFO - Installing distribute.............................................................................................................................................................................................done. 22:16:55 INFO - Installing pip.................done. 22:16:56 INFO - Return code: 0 22:16:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:16:56 INFO - Reading from file tmpfile_stdout 22:16:56 INFO - Output received: 22:16:56 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 22:16:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:16: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')]} 22:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16: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')]} 22:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dfdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c01570be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddf1d30>, '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 0x10dc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dde9b20>, '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.w5EbPZNsH3/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.o3Jtyjr5nh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:16:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:16:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5EbPZNsH3/Render', 22:16:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:16:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:16:56 INFO - 'HOME': '/Users/cltbld', 22:16:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:16:56 INFO - 'LOGNAME': 'cltbld', 22:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:56 INFO - 'MOZ_NO_REMOTE': '1', 22:16:56 INFO - 'NO_EM_RESTART': '1', 22:16:56 INFO - 'PAGER': '/bin/cat', 22:16:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:56 INFO - 'PWD': '/builds/slave/test', 22:16:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:16:56 INFO - 'SHELL': '/bin/bash', 22:16:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3Jtyjr5nh/Listeners', 22:16:56 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 22:16:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:16:56 INFO - 'USER': 'cltbld', 22:16:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:16:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:56 INFO - 'XPC_FLAGS': '0x0', 22:16:56 INFO - 'XPC_SERVICE_NAME': '0', 22:16:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:16:56 INFO - Cleaning up... 22:16:56 INFO - Return code: 0 22:16:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:16: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')]} 22:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16: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')]} 22:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dfdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c01570be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddf1d30>, '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 0x10dc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dde9b20>, '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.w5EbPZNsH3/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.o3Jtyjr5nh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:16:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:16:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5EbPZNsH3/Render', 22:16:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:16:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:16:56 INFO - 'HOME': '/Users/cltbld', 22:16:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:16:56 INFO - 'LOGNAME': 'cltbld', 22:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:56 INFO - 'MOZ_NO_REMOTE': '1', 22:16:56 INFO - 'NO_EM_RESTART': '1', 22:16:56 INFO - 'PAGER': '/bin/cat', 22:16:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:56 INFO - 'PWD': '/builds/slave/test', 22:16:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:16:56 INFO - 'SHELL': '/bin/bash', 22:16:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3Jtyjr5nh/Listeners', 22:16:56 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 22:16:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:16:56 INFO - 'USER': 'cltbld', 22:16:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:16:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:56 INFO - 'XPC_FLAGS': '0x0', 22:16:56 INFO - 'XPC_SERVICE_NAME': '0', 22:16:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:56 INFO - Downloading/unpacking psutil>=3.1.1 22:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:17:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:17:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:17:00 INFO - Installing collected packages: psutil 22:17:00 INFO - Running setup.py install for psutil 22:17:00 INFO - building 'psutil._psutil_osx' extension 22:17:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:17:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:17:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 22:17:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 22:17:00 INFO - building 'psutil._psutil_posix' extension 22:17:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 22:17:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:17:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:17:00 INFO - ^ 22:17:01 INFO - 1 warning generated. 22:17:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 22:17:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:17:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:17:01 INFO - Successfully installed psutil 22:17:01 INFO - Cleaning up... 22:17:01 INFO - Return code: 0 22:17:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:17:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dfdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c01570be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddf1d30>, '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 0x10dc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dde9b20>, '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.w5EbPZNsH3/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.o3Jtyjr5nh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:17:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:17:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:17:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5EbPZNsH3/Render', 22:17:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:01 INFO - 'HOME': '/Users/cltbld', 22:17:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:01 INFO - 'LOGNAME': 'cltbld', 22:17:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:01 INFO - 'MOZ_NO_REMOTE': '1', 22:17:01 INFO - 'NO_EM_RESTART': '1', 22:17:01 INFO - 'PAGER': '/bin/cat', 22:17:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:01 INFO - 'PWD': '/builds/slave/test', 22:17:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:01 INFO - 'SHELL': '/bin/bash', 22:17:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3Jtyjr5nh/Listeners', 22:17:01 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 22:17:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:01 INFO - 'USER': 'cltbld', 22:17:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:01 INFO - 'XPC_FLAGS': '0x0', 22:17:01 INFO - 'XPC_SERVICE_NAME': '0', 22:17:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:17:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:17:04 INFO - Installing collected packages: mozsystemmonitor 22:17:04 INFO - Running setup.py install for mozsystemmonitor 22:17:04 INFO - Successfully installed mozsystemmonitor 22:17:04 INFO - Cleaning up... 22:17:04 INFO - Return code: 0 22:17:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dfdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c01570be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddf1d30>, '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 0x10dc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dde9b20>, '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.w5EbPZNsH3/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.o3Jtyjr5nh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:17:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:17:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:17:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5EbPZNsH3/Render', 22:17:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:04 INFO - 'HOME': '/Users/cltbld', 22:17:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:04 INFO - 'LOGNAME': 'cltbld', 22:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:04 INFO - 'MOZ_NO_REMOTE': '1', 22:17:04 INFO - 'NO_EM_RESTART': '1', 22:17:04 INFO - 'PAGER': '/bin/cat', 22:17:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:04 INFO - 'PWD': '/builds/slave/test', 22:17:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:04 INFO - 'SHELL': '/bin/bash', 22:17:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3Jtyjr5nh/Listeners', 22:17:04 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 22:17:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:04 INFO - 'USER': 'cltbld', 22:17:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:04 INFO - 'XPC_FLAGS': '0x0', 22:17:04 INFO - 'XPC_SERVICE_NAME': '0', 22:17:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:04 INFO - Downloading/unpacking jsonschema==2.5.1 22:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:17:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:07 INFO - Downloading functools32-3.2.3-2.tar.gz 22:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:17:07 INFO - warning: no files found matching '*.txt' 22:17:07 INFO - no previously-included directories found matching 'build' 22:17:07 INFO - no previously-included directories found matching 'dist' 22:17:07 INFO - no previously-included directories found matching '.git*' 22:17:07 INFO - Installing collected packages: jsonschema, functools32 22:17:07 INFO - Running setup.py install for functools32 22:17:07 INFO - warning: no files found matching '*.txt' 22:17:07 INFO - no previously-included directories found matching 'build' 22:17:07 INFO - no previously-included directories found matching 'dist' 22:17:07 INFO - no previously-included directories found matching '.git*' 22:17:07 INFO - Successfully installed jsonschema functools32 22:17:07 INFO - Cleaning up... 22:17:07 INFO - Return code: 0 22:17:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:07 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 0x10dfdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c01570be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddf1d30>, '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 0x10dc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dde9b20>, '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.w5EbPZNsH3/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.o3Jtyjr5nh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:17:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:17:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:17:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5EbPZNsH3/Render', 22:17:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:07 INFO - 'HOME': '/Users/cltbld', 22:17:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:07 INFO - 'LOGNAME': 'cltbld', 22:17:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:07 INFO - 'MOZ_NO_REMOTE': '1', 22:17:07 INFO - 'NO_EM_RESTART': '1', 22:17:07 INFO - 'PAGER': '/bin/cat', 22:17:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:07 INFO - 'PWD': '/builds/slave/test', 22:17:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:07 INFO - 'SHELL': '/bin/bash', 22:17:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3Jtyjr5nh/Listeners', 22:17:07 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 22:17:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:07 INFO - 'USER': 'cltbld', 22:17:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:07 INFO - 'XPC_FLAGS': '0x0', 22:17:07 INFO - 'XPC_SERVICE_NAME': '0', 22:17:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:17:08 INFO - Cleaning up... 22:17:08 INFO - Return code: 0 22:17:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:17: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')]} 22:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17: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')]} 22:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dfdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c01570be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddf1d30>, '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 0x10dc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dde9b20>, '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.w5EbPZNsH3/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.o3Jtyjr5nh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:17:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:17:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:17:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5EbPZNsH3/Render', 22:17:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:08 INFO - 'HOME': '/Users/cltbld', 22:17:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:08 INFO - 'LOGNAME': 'cltbld', 22:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:08 INFO - 'MOZ_NO_REMOTE': '1', 22:17:08 INFO - 'NO_EM_RESTART': '1', 22:17:08 INFO - 'PAGER': '/bin/cat', 22:17:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:08 INFO - 'PWD': '/builds/slave/test', 22:17:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:08 INFO - 'SHELL': '/bin/bash', 22:17:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3Jtyjr5nh/Listeners', 22:17:08 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 22:17:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:08 INFO - 'USER': 'cltbld', 22:17:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:08 INFO - 'XPC_FLAGS': '0x0', 22:17:08 INFO - 'XPC_SERVICE_NAME': '0', 22:17:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:08 INFO - Downloading/unpacking blobuploader==1.2.4 22:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:10 INFO - Downloading blobuploader-1.2.4.tar.gz 22:17:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:17:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:17:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:12 INFO - Downloading docopt-0.6.1.tar.gz 22:17:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:17:12 INFO - Installing collected packages: blobuploader, requests, docopt 22:17:12 INFO - Running setup.py install for blobuploader 22:17:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:17:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:17:12 INFO - Running setup.py install for requests 22:17:12 INFO - Running setup.py install for docopt 22:17:12 INFO - Successfully installed blobuploader requests docopt 22:17:12 INFO - Cleaning up... 22:17:13 INFO - Return code: 0 22:17:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dfdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c01570be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddf1d30>, '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 0x10dc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dde9b20>, '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.w5EbPZNsH3/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.o3Jtyjr5nh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:17:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:17:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5EbPZNsH3/Render', 22:17:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:13 INFO - 'HOME': '/Users/cltbld', 22:17:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:13 INFO - 'LOGNAME': 'cltbld', 22:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:13 INFO - 'MOZ_NO_REMOTE': '1', 22:17:13 INFO - 'NO_EM_RESTART': '1', 22:17:13 INFO - 'PAGER': '/bin/cat', 22:17:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:13 INFO - 'PWD': '/builds/slave/test', 22:17:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:13 INFO - 'SHELL': '/bin/bash', 22:17:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3Jtyjr5nh/Listeners', 22:17:13 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 22:17:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:13 INFO - 'USER': 'cltbld', 22:17:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:13 INFO - 'XPC_FLAGS': '0x0', 22:17:13 INFO - 'XPC_SERVICE_NAME': '0', 22:17:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:17:13 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-_T9J7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:17:13 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-D2npfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:17:13 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-77yXGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:17:13 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-MGCtLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:17:13 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-LsGOwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:17:13 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-jJQx3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:17:13 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-0hVcJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:17:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-JD3Di7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:17:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-V2jzgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:17:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-6tcHY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:17:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-1GduD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:17:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-bv5Vfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:17:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-RoeiGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:17:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-st6E3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-RxQkwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:17:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-TDxG0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:17:14 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-2cyImV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:17:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:17:15 INFO - Running setup.py install for manifestparser 22:17:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:17:15 INFO - Running setup.py install for mozcrash 22:17:15 INFO - Running setup.py install for mozdebug 22:17:15 INFO - Running setup.py install for mozdevice 22:17:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:17:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:17:15 INFO - Running setup.py install for mozfile 22:17:15 INFO - Running setup.py install for mozhttpd 22:17:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:17:15 INFO - Running setup.py install for mozinfo 22:17:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:17:16 INFO - Running setup.py install for mozInstall 22:17:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:17:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:17:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:17:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:17:16 INFO - Running setup.py install for mozleak 22:17:16 INFO - Running setup.py install for mozlog 22:17:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:17:16 INFO - Running setup.py install for moznetwork 22:17:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:17:16 INFO - Running setup.py install for mozprocess 22:17:16 INFO - Running setup.py install for mozprofile 22:17:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:17:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:17:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:17:17 INFO - Running setup.py install for mozrunner 22:17:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:17:17 INFO - Running setup.py install for mozscreenshot 22:17:17 INFO - Running setup.py install for moztest 22:17:17 INFO - Running setup.py install for mozversion 22:17:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:17:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:17:17 INFO - Cleaning up... 22:17:17 INFO - Return code: 0 22:17:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:17 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 0x10dfdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c01570be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddf1d30>, '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 0x10dc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dde9b20>, '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.w5EbPZNsH3/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.o3Jtyjr5nh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:17:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:17:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5EbPZNsH3/Render', 22:17:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:17 INFO - 'HOME': '/Users/cltbld', 22:17:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:17 INFO - 'LOGNAME': 'cltbld', 22:17:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:17 INFO - 'MOZ_NO_REMOTE': '1', 22:17:17 INFO - 'NO_EM_RESTART': '1', 22:17:17 INFO - 'PAGER': '/bin/cat', 22:17:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:17 INFO - 'PWD': '/builds/slave/test', 22:17:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:17 INFO - 'SHELL': '/bin/bash', 22:17:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3Jtyjr5nh/Listeners', 22:17:17 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 22:17:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:17 INFO - 'USER': 'cltbld', 22:17:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:17 INFO - 'XPC_FLAGS': '0x0', 22:17:17 INFO - 'XPC_SERVICE_NAME': '0', 22:17:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:17:17 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-TbvRIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:17:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-bl3Lp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:17:18 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)) 22:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:17:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-9aJQJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:17:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-YW2qHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:17:18 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)) 22:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:17:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-EZLAtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:17:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-Yk3kKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:17:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-umPSLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:17:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-_U2s7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:17:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-RntsXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:17:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-DWc8r3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:17:18 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-yxFnDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:17:19 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-aviKMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:17:19 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-iAwh7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:17:19 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-IpiJaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:17:19 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)) 22:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:19 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-bQOAzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:17:19 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-UhYeCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:17:19 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)) 22:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:17:19 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-GXtebF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:17:19 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)) 22:17:19 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)) 22:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:17:19 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)) 22:17:19 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)) 22:17:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:22 INFO - Downloading blessings-1.6.tar.gz 22:17:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:17:22 INFO - Installing collected packages: blessings 22:17:22 INFO - Running setup.py install for blessings 22:17:22 INFO - Successfully installed blessings 22:17:22 INFO - Cleaning up... 22:17:22 INFO - Return code: 0 22:17:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:17:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:17:22 INFO - Reading from file tmpfile_stdout 22:17:22 INFO - Current package versions: 22:17:22 INFO - blessings == 1.6 22:17:22 INFO - blobuploader == 1.2.4 22:17:22 INFO - docopt == 0.6.1 22:17:22 INFO - functools32 == 3.2.3-2 22:17:22 INFO - jsonschema == 2.5.1 22:17:22 INFO - manifestparser == 1.1 22:17:22 INFO - mozInstall == 1.12 22:17:22 INFO - mozcrash == 0.17 22:17:22 INFO - mozdebug == 0.1 22:17:22 INFO - mozdevice == 0.48 22:17:22 INFO - mozfile == 1.2 22:17:22 INFO - mozhttpd == 0.7 22:17:22 INFO - mozinfo == 0.9 22:17:22 INFO - mozleak == 0.1 22:17:22 INFO - mozlog == 3.4 22:17:22 INFO - moznetwork == 0.27 22:17:22 INFO - mozprocess == 0.23 22:17:22 INFO - mozprofile == 0.28 22:17:22 INFO - mozrunner == 6.12 22:17:22 INFO - mozscreenshot == 0.1 22:17:22 INFO - mozsystemmonitor == 0.3 22:17:22 INFO - moztest == 0.7 22:17:22 INFO - mozversion == 1.4 22:17:22 INFO - psutil == 3.1.1 22:17:22 INFO - requests == 1.2.3 22:17:22 INFO - wsgiref == 0.1.2 22:17:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:22 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 0x10dfdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c01570be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddf1d30>, '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 0x10dc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dde9b20>, '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.w5EbPZNsH3/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.o3Jtyjr5nh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:17:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:17:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5EbPZNsH3/Render', 22:17:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:22 INFO - 'HOME': '/Users/cltbld', 22:17:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:22 INFO - 'LOGNAME': 'cltbld', 22:17:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:22 INFO - 'MOZ_NO_REMOTE': '1', 22:17:22 INFO - 'NO_EM_RESTART': '1', 22:17:22 INFO - 'PAGER': '/bin/cat', 22:17:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:22 INFO - 'PWD': '/builds/slave/test', 22:17:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:22 INFO - 'SHELL': '/bin/bash', 22:17:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3Jtyjr5nh/Listeners', 22:17:22 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 22:17:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:22 INFO - 'USER': 'cltbld', 22:17:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:22 INFO - 'XPC_FLAGS': '0x0', 22:17:22 INFO - 'XPC_SERVICE_NAME': '0', 22:17:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:17:23 INFO - Cleaning up... 22:17:23 INFO - Return code: 0 22:17:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dfdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c01570be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddf1d30>, '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 0x10dc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dde9b20>, '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.w5EbPZNsH3/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.o3Jtyjr5nh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:17:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:17:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5EbPZNsH3/Render', 22:17:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:23 INFO - 'HOME': '/Users/cltbld', 22:17:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:23 INFO - 'LOGNAME': 'cltbld', 22:17:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:23 INFO - 'MOZ_NO_REMOTE': '1', 22:17:23 INFO - 'NO_EM_RESTART': '1', 22:17:23 INFO - 'PAGER': '/bin/cat', 22:17:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:23 INFO - 'PWD': '/builds/slave/test', 22:17:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:23 INFO - 'SHELL': '/bin/bash', 22:17:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3Jtyjr5nh/Listeners', 22:17:23 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 22:17:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:23 INFO - 'USER': 'cltbld', 22:17:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:23 INFO - 'XPC_FLAGS': '0x0', 22:17:23 INFO - 'XPC_SERVICE_NAME': '0', 22:17:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:17:24 INFO - Cleaning up... 22:17:24 INFO - Return code: 0 22:17:24 INFO - Running post-action listener: _resource_record_post_action 22:17:24 INFO - Running post-action listener: _start_resource_monitoring 22:17:24 INFO - Starting resource monitoring. 22:17:24 INFO - [mozharness: 2016-10-28 05:17:24.266767Z] Finished create-virtualenv step (success) 22:17:24 INFO - [mozharness: 2016-10-28 05:17:24.267764Z] Running install step. 22:17:24 INFO - Running pre-action listener: _resource_record_pre_action 22:17:24 INFO - Running main action method: install 22:17:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:17:24 INFO - Reading from file tmpfile_stdout 22:17:24 INFO - Detecting whether we're running mozinstall >=1.0... 22:17:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:17:24 INFO - Reading from file tmpfile_stdout 22:17:24 INFO - Output received: 22:17:24 INFO - Usage: mozinstall [options] installer 22:17:24 INFO - Options: 22:17:24 INFO - -h, --help show this help message and exit 22:17:24 INFO - -d DEST, --destination=DEST 22:17:24 INFO - Directory to install application into. [default: 22:17:24 INFO - "/builds/slave/test"] 22:17:24 INFO - --app=APP Application being installed. [default: firefox] 22:17:24 INFO - mkdir: /builds/slave/test/build/application 22:17:24 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'] 22:17:24 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 22:17:58 INFO - Reading from file tmpfile_stdout 22:17:58 INFO - Output received: 22:17:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:17:58 INFO - Running post-action listener: _resource_record_post_action 22:17:58 INFO - [mozharness: 2016-10-28 05:17:58.735817Z] Finished install step (success) 22:17:58 INFO - [mozharness: 2016-10-28 05:17:58.735945Z] Running run-tests step. 22:17:58 INFO - Running pre-action listener: _resource_record_pre_action 22:17:58 INFO - Running main action method: run_tests 22:17:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:17:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:17:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:17:58 INFO - 2016-10-27 22:17:58.750 screenresolution[1924:13844] starting screenresolution argv=screenresolution get 22:17:58 INFO - 2016-10-27 22:17:58.753 screenresolution[1924:13844] Display 0: 1600x1200x32@60 22:17:58 INFO - 2016-10-27 22:17:58.759 screenresolution[1925:13846] starting screenresolution argv=screenresolution list 22:17:58 INFO - Available Modes on Display 0 22:17:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:17:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:17:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:17:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:17:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:17:58 INFO - Intel Iris: 22:17:58 INFO - Chipset Model: Intel Iris 22:17:58 INFO - Type: GPU 22:17:58 INFO - Bus: Built-In 22:17:58 INFO - VRAM (Dynamic, Max): 1536 MB 22:17:58 INFO - Vendor: Intel (0x8086) 22:17:58 INFO - Device ID: 0x0a2e 22:17:58 INFO - Revision ID: 0x0009 22:17:58 INFO - Displays: 22:17:58 INFO - Display: 22:17:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:17:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:17:58 INFO - Display Serial Number: 1600x1200 60 22:17:58 INFO - Main Display: Yes 22:17:58 INFO - Mirror: Off 22:17:58 INFO - Online: Yes 22:17:58 INFO - Rotation: Supported 22:17:58 INFO - Return code: 0 22:17:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:17:58 INFO - Python 2.7.3 22:17:58 INFO - Return code: 0 22:17:58 INFO - grabbing minidump binary from tooltool 22:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:58 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 0x10ddf1d30>, '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 0x10dc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dde9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:17:58 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:17:58 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 22:17:58 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:17:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:17:59 INFO - Return code: 0 22:17:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:17:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:17:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:17:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:17:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:17:59 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/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0310', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:17:59 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/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0310 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:17:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5EbPZNsH3/Render', 22:17:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:59 INFO - 'HOME': '/Users/cltbld', 22:17:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:59 INFO - 'LOGNAME': 'cltbld', 22:17:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:17:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:17:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:59 INFO - 'MOZ_NO_REMOTE': '1', 22:17:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:17:59 INFO - 'NO_EM_RESTART': '1', 22:17:59 INFO - 'PAGER': '/bin/cat', 22:17:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:59 INFO - 'PWD': '/builds/slave/test', 22:17:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:17:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:59 INFO - 'SHELL': '/bin/bash', 22:17:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3Jtyjr5nh/Listeners', 22:17:59 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 22:17:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:59 INFO - 'USER': 'cltbld', 22:17:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:59 INFO - 'XPC_FLAGS': '0x0', 22:17:59 INFO - 'XPC_SERVICE_NAME': '0', 22:17:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:59 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/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0310', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:17:59 INFO - mozversion application_buildid: 20161027192219 22:17:59 INFO - mozversion application_changeset: 944cb0fd05526894fcd90fbe7d1e625ee53cd73d 22:17:59 INFO - mozversion application_display_name: Nightly 22:17:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:17:59 INFO - mozversion application_name: Firefox 22:17:59 INFO - mozversion application_remotingname: firefox 22:17:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:17:59 INFO - mozversion application_vendor: Mozilla 22:17:59 INFO - mozversion application_version: 52.0a1 22:17:59 INFO - mozversion platform_buildid: 20161027192219 22:17:59 INFO - mozversion platform_changeset: 944cb0fd05526894fcd90fbe7d1e625ee53cd73d 22:17:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:17:59 INFO - mozversion platform_version: 52.0a1 22:17:59 INFO - using testdate: 1477631879 22:17:59 INFO - actual date: 1477631879 22:17:59 INFO - starting webserver on 'localhost:51318' 22:17:59 INFO - SUITE-START | Running 2 tests 22:17:59 INFO - TEST-START | dromaeo_css 22:17:59 INFO - operating with platform_type : mac_ 22:17:59 INFO - Initialising browser for dromaeo_css test... 22:17:59 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmp_60K7L/profile http://localhost:51318/getInfo.html) 22:18:02 INFO - PROCESS | 1935 | 2016-10-27 22:18:01.997 plugin-container[1937:14007] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9143, name = 'com.apple.tsm.portname' 22:18:02 INFO - PROCESS | 1935 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:18:02 INFO - PROCESS | 1935 | 2016-10-27 22:18:02.000 plugin-container[1937:14007] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950f, name = 'com.apple.CFPasteboardClient' 22:18:02 INFO - PROCESS | 1935 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:18:02 INFO - PROCESS | 1935 | 2016-10-27 22:18:02.000 plugin-container[1937:14007] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:18:02 INFO - PROCESS | 1935 | __metrics Screen width/height:1600/1200 22:18:02 INFO - PROCESS | 1935 | colorDepth:24 22:18:02 INFO - PROCESS | 1935 | Browser inner width/height: 1024/689 22:18:02 INFO - PROCESS | 1935 | __metrics 22:18:02 ERROR - PROCESS | 1935 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 22:18:02 INFO - TEST-INFO | 1935: exit 0 22:18:02 INFO - Browser initialized. 22:18:02 INFO - Running cycle 1/1 for dromaeo_css test... 22:18:02 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmp_60K7L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:18:05 INFO - PROCESS | 1938 | 2016-10-27 22:18:05.430 plugin-container[1939:14127] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 22:18:05 INFO - PROCESS | 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:18:05 INFO - PROCESS | 1938 | 2016-10-27 22:18:05.431 plugin-container[1939:14127] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 22:18:05 INFO - PROCESS | 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:18:05 INFO - PROCESS | 1938 | 2016-10-27 22:18:05.431 plugin-container[1939:14127] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:18:15 INFO - PROCESS | 1938 | RSS: Main: 236113920 22:18:15 INFO - PROCESS | 1938 | 22:19:04 INFO - PROCESS | 1938 | 1477631944779 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] 22:21:17 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51318/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51318/tests/dromaeo/cssquery-ext.html) 22:21:19 INFO - PROCESS | 1938 | RSS: Main: 250826752 22:21:19 INFO - PROCESS | 1938 | 22:24:21 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51318/tests/dromaeo/cssquery-ext.html (next: http://localhost:51318/tests/dromaeo/cssquery-jquery.html) 22:24:22 INFO - PROCESS | 1938 | RSS: Main: 261148672 22:24:22 INFO - PROCESS | 1938 | 22:27:24 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51318/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51318/tests/dromaeo/cssquery-mootools.html) 22:27:24 INFO - PROCESS | 1938 | RSS: Main: 265515008 22:27:24 INFO - PROCESS | 1938 | 22:30:26 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51318/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51318/tests/dromaeo/cssquery-prototype.html) 22:30:27 INFO - PROCESS | 1938 | RSS: Main: 264056832 22:30:27 INFO - PROCESS | 1938 | 22:33:30 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51318/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51318/tests/dromaeo/cssquery-yui.html) 22:33:31 INFO - PROCESS | 1938 | RSS: Main: 268431360 22:33:31 INFO - PROCESS | 1938 | 22:36:33 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51318/tests/dromaeo/cssquery-yui.html (next: http://localhost:51318/tests/dromaeo/cssquery-dojo.html) 22:36:34 INFO - PROCESS | 1938 | RSS: Main: 270450688 22:36:34 INFO - PROCESS | 1938 | 22:36:34 INFO - PROCESS | 1938 | __start_tp_report 22:36:34 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 22:36:34 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 22:36:34 INFO - PROCESS | 1938 | |i|pagename|runs| 22:36:34 INFO - PROCESS | 1938 | |0;dojo.html;2915.2566095645607;3268.477043673012;3385.22139907821;3391.203067279189;3427.1774774054234;4592.448906131261;5593.74828132734;5631.239782629344;5676.801311954087;5760.889119875263;5613.045782217023;5629.60592758506;5753.165790960308;5763.625364351315;5813.040848260032;5135.204043373283;5181.559567436753;5233.267342572043;5272.393674727398;5297.364316282052;5179.171332586787;5243.4232234454175;5287.101192797234;5291.2327712481665;5365.141577347626;5265.18389649603;5277.47225277473;5296.496832800878;5296.92054619181;5328.307320048389;5097.184450487941;5148.176291793295;5171.81898633546;5200.739963001834;5217.191335343028;5008.348914641109;5107.9744601277125;5146.408163061258;5227.451117632631;5271.499207575274;4020.155767288873;5175.974120129413;5276.3140791697215;5742.913856292159;5779.739911703983;5422.457754224585;6164.907526387108;6190.81427557174;6237.1891654084675;6279.089532017878;6180.690965451709;6228.5951413158;6230.283517395467;6238.5944913580925;6245.156903817971;5948.524118070587;5973.820785376446;5988.730507127189;5989.970050149721;6019.217501724747;6165.691715414211;6219.067139929047;6227;6246.937530624636;6396.808095757135;6171.197744293213;6175.536834737412;6176.722047507873;6181.474574661204;6200.286966998763;6246.437820596168;6254.312025677172;6259.436650701458;6265.71804268809;6266.624002559861;6072.453479186895;6105.450509454082;6151.7539298428455;6216.160818289563;6240.781572644935;6125.938740612537;6173.382661733881;6215.378462153749;6221.191245138103;6264.060390941395;4179.97910010448;4951.628627852925;4962.950370496249;5002;5020.548150666457;4456.242438785414;4458.977705111453;4506.909861802746;4509.819607215741;4526.275795872644;5659.688717120515;5666.291713535808;5810.4189581041555;5838.9124163137585;5877.94122058774;6190.7523699052435;6260.060990851409;6268.216472940882;6279;6280.560360774702;5885.323187833454;5911.556633252523;5913.704314784243;5915.911261331084;5979.701014949235;5238.476152384807;5286.312779338661;5391.353037635509;5399.595030372738;5431.782728690808;6425.004124360731;6425.903611445926;6440.25937017249;6457.61254324742;6515.576487528296;6272.278687950944;6272.40412160844;6272.466840318624;6277.869983403031;6285.622862628257;4789.329493870791;5428.104362780097;5602.859928501657;5743.368229494668;5922.407759224043;4727;5016.673916195436;6097.222222222223;6143.880361275353;6147.262328520606;6133.885575987568;6149.569530133027;6187.597806142587;6243.96878015607;6300.495960323072;5421.864453388697;5816.6330912236435;6094.78668246627;6162.352952940056;6168.506519478521;5985.880282394415;6116.724747386467;6120.479759220516;6175.938240617536;6185.567010309415;5813.273340832396;5847.561432892465;5868.911966320423;6005.729742161526;6129.570930034854;5959.76160953557;6004.1294012368835;6100.786472473442;6121.326654068138;6160.630362178284;6000.429959153874;6004.42957918997;6016.699165041818;6039.4262545058145;6086.6652334122045 22:36:34 INFO - PROCESS | 1938 | |1;ext.html;470.20535295350857;510.9576464013408;524.9186376111702;527.9920801187983;577.2178697864391;37340.81329593352;39176.41235381472;40505;40668.59331406685;40706.59293407065;41405;41941;41992;42131.15737685252;42196.15607687845;9393.859092113607;9509.286803489731;9589.712308630717;9592.568334424968;9628.18160456362;9422.905770942272;9447.527623618791;9484.620615175385;9509.286803489766;9525.5713492893;9481.383710058859;9530.903946046225;9533.523323833815;9548.61805527778;9564.091411315914;9448.71653850382;9487.620495180185;9495.382800118039;9509.857352139723;9537.809243815154;7986.440949133563;9417.24662027043;9446.244300455946;9465.526723663857;9504.572294246775;39372.80313598413;39496.40755388672;39595.60404395948;39700;39772.60227397747;3325.268750155873;3623.0398944279787;3890.2997460457104;3897.493325867619;4164.541900390955;30370.84814575913;31581.052568422983;31602.525962110587;31767.52348714771;31795.364092718017;3205.022468147214;3307.96692033079;3327.8336083195745;3356.848941797625;3375.1730825947557;398093;501875;506133;507224;507491;48090.5190948093;61165.694171528856;61216.775664486464;61515;61884.38115618876;52886;55168.034876861275;56286;65099;65159;15993.440229591908;16101.27544260511;16297.348106075624;16331;16382.262798174112;12413.813792793115;12427.130100892851;12517.248965062125;12528.624141275777;12570.308633025272;15846.60383490422;17044.403445879336;17286.913565432093;17363.565910852078;17602.03188824627;7419.406447484188;7466.440016998638;7508.812279693051;7522.811929701692;7556.4710470266555;832.5878690048464;845.6067928413318;933.9912894074383;973.3089506450499;1062.0919114157439;25504.617430738555;26556.60165097525;26576.601350979752;30186;31130;1055;1079.4602698650674;1122.93262404256;1137.988620113805;1144.891235332642;15302.158381288913;15455.30451129702;15551.377944882304;15851.445199417965;16123.274452649659;638937;642071;644093;645840;650053;59829.700851495465;59989.80020399568;60080.098798518055;60178.69910650419;60194;1368.9931550342185;1383.9515616953358;1387.3271463340272;1406.2476575032308;1414.3776738234938;29390.706092939654;30422.93519726799;30568.388632227674;30778.21361200097;31019.534706979866;51425.22862156996;51979.48020519746;52085.7395713019;52170.739146304026;52356.73821630867;479878;589105;591937;593217;594601;22296.88851555732;26600.733992659825;26832.19503414901;27345.453090938467;27353.726462735118;47756;48258;48336.758316208194;48889.511104888494;49314.75342623264;39638.405423918084;39781.237189677246;40317.476114648984;40793.184136316515;41098.38352424776;38136;39419.80290098531;39757.60242397655;40275;40324.79837600793;38321.80839095786;38889;39006.6099339003;39030;39162.80418598003;32798;34357.87705475359;34548.30903381868;34846.47730283997;35080.47379289364;21180.629477614202;21626;24024.39939001539;25825;25970.480590388463 22:36:34 INFO - PROCESS | 1938 | |2;jquery.html;371.9088823238307;380.6250842919724;384.42386216512296;387.2004311097585;402.794574766869;18991.95544245066;20146.294879679215;21607.891960540193;21822.672659910073;21919;20026.49933751657;20296.594068118666;20776.584468310662;20792.792072079275;21216.04527796249;5120.231965205221;5249.711265880375;5907.172995780573;6009.1286763419275;6009.369016253274;3799.164183879556;4015.738976966488;5822.301323841145;5930.288365396159;5944.940550594503;6210.2547694276755;6536.052272420497;6580.276169621337;6625.17185351831;6625.211192977893;6355.618662880219;6514.869702605922;6572.5399222054475;6581.901271480884;6623.801285961405;5494.1758736189495;5751.281089863767;5814.534837213013;5824.068149096124;5827.096799996008;18804;20845.8534780587;25393.873030634913;25546.48907021868;26039.479210415684;1756.6311074674334;2218.1792736687366;2278.544291141778;2387.78509934105;2637.788976881845;10025.949870250675;10074.496275186284;10354.326968747082;10444.060034596847;14964.176970266632;1765.9911700441417;1801.8378345948927;1858.8327050565379;1865.2818664814145;1901.1729897494495;126484;129703;132663;132782.33608831893;133750;9265.7954465919;11478.655640330804;11682.474288657031;11709.765804683859;11799.233049851731;12538.310392928477;14091.929540352437;14668.193249371387;14759.557213283619;14827;8247.752567422987;8482.151784821592;8532.488050716976;8663.826723465496;8689.13108689126;8350.832983340299;8380.16198380157;8386.454880432751;8446.915530844735;8509;4577.793999270041;4581.427321584802;4604.401427814362;4612.630989520831;4645.790939407735;1598.6482973745758;1682.9831701683072;1903.9714404283948;1980.9306674266331;1988.0656091637197;799.8880156778056;960.6973803251954;1002.9749256268651;1015.720676813882;1064.776396956648;18613.81386186148;20607.381778546667;20965.37103886886;21046.579068418545;21143.25998590042;932.1889955738551;948.4546385828038;980.5930538826307;1009.7071849163807;1010.1968934696934;8739.12848225368;10821.783564328827;10987.230893837355;11053.392063436588;11090.334579925231;585878;585968;589644.0517797469;593327.5671567878;603066;106191.46904265584;126211;130451;130709;132673.0099048515;991.9305648604528;997.6159178716101;1010.2271762101998;1024.08856118055;1027.3733022856052;18200.908995454938;18247;18583.81416185875;18712.90643546828;18802;56293.71853140708;56341;56405.58986025218;56924.14613780879;57139.71430142823;58551.70724146352;79864.4027119466;80383.79424308757;81152.5942370308;81483;7216.0258365120035;7288.781336559911;7370.889436658562;7438.144613369315;7527.774166775005;9586.376885502421;10371.481425928523;10518.684439466828;12522.06084543681;12894.419751111034;8771.736847894574;10037.19702423819;11470.942645286721;11526.827097593376;11675.88324116748;9036.593353298986;11322.830157547478;11360.772784544097;11429.371384573928;11769.882301177222;11254.94372528132;11473.196876218584;11484.94257528707;11686.532538698357;11930.582429614922;8106.635201415833;8145.348372130334;8406;8487.066422693622;8591.355648326275;9852.211823054282;11480.540778369106;11608.709782255512;11676;11692.883071169179 22:36:34 INFO - PROCESS | 1938 | |3;mootools.html;1563.4762354611205;1594.4897632757516;1869.2429566025755;1919.4337670387235;1962.9607407851843;7657.731979380723;7952.443328966975;8195.508269503835;8692.608832602533;8697.695580654687;6921.927101299307;7640.656170472328;7953.3695592403565;8581;8605.612747426365;2165.545235500547;2336.8247381446376;2339;2349.5888219561593;2349.7297810751725;2319.8492098013658;2346.9765302347014;2367.8342516023886;2374.7625237476286;2386.606209975361;1808.2586139682733;2109.945027486257;2312.1213938703268;2314.2247347138727;2336.789688927996;2301.355620426283;2304.37781798915;2329.3477826208773;2331.4287999440244;2333.381653861728;1928.5660726336603;2330.8484948478294;2353.705786776653;2355;2359.988200059006;8546.316294696471;8611.956940215321;8646.870296945488;8661.783455413602;8698.21716045559;5200.323957885488;5364.6512976656395;5371.59713021521;5389.892202155974;5412.864678383033;3310.9503357449657;3385.339858727549;3647.7993710345922;3657.5062366580705;3657.7439579229463;927.8793756811639;985.5515740338129;1057.8783439904432;1071.5017516854623;1077.488193108275;11080.667579972614;11083.224174307801;11148.944255278671;11178.776424471465;11194.552217911356;4532.592066714012;4605.654575906753;4675.18184317743;4687.882802929886;4800.231962885921;9576.138147566613;9639.132478077006;9640.180584650383;9663.130318271249;9698.321117521848;5313.57491400687;5338;5339.466053394707;5341.65279256847;5387.434319396485;4036.273470775289;4372.890677733082;4447.199504089295;4882.707037577757;4901.632377571696;3814.6376094271;3872.670822980022;3907.159960608426;3955.3078211312904;3976.1848820991627;1264.2414551269164;1273.5287943460978;1278.565287802133;1286.7104901397297;1289.1233960906486;842.3850589070013;913.794396260836;924.4638109896244;935.5649622925372;952.3809523809441;5655.236543066633;5836.9124463133085;5875;5910.734016969247;5921.96365636012;426.30299460382497;433.9067100573392;436.73795722566206;449.29909341427475;452.2673269303683;3884.475595794588;3923.9803800981385;3952.7233093683167;3986.9202615948097;3998.520177578709;8565.571721413904;9959.601615935282;10314.845277320846;10352.327098738559;10361.513236823108;10159.847602285972;10299.433531155859;10317.638882639072;10333.74165645865;10335.17318614509;7644;7980.84038319242;8016.599170041358;8114.513129212266;8117.715879944291;4773.116973359801;5623.8594035149445;5879.353271140257;5942.524598032233;6002.189704389852;8947.686830960885;9326.373104878096;9369.297302702189;9408.529573521433;9417.623295068395;9423.481708506099;9459.858102128612;9654.71035868925;9716.368436051634;9727.221822254343;6696.363845434676;6885.345892140239;7126.073610430611;7237.674304656198;7408.370288525536;7921.881171782313;8057.83884322322;8146.103928567734;8163.959180204061;8174.305184059422;7191.71233150689;7338.266173382619;7353;7393.8521229574035;7449.553026818408;7022.192447868356;7102.431805455654;7168;7179.210286868545;7327.926720732938;6737.528373013842;6876.484263680144;6901.344372284625;6911.30886911325;6916.170059592881;4810.278458231293;4814.855554333376;4924.852254432372;4998.325226094431;5007.649464537448;4880.731559764247;4967.975160124176;5014.247862820606;5073.365829271341;5102.336696229467 22:36:34 INFO - PROCESS | 1938 | |4;prototype.html;300.693292841302;305.252132275924;311.29336406357567;314.4701178514205;318.6749515494196;4497.370368148458;4934.383202099738;5122.154844450662;5478.808241711541;5601.815292036109;5192.065428222918;5331.280277162582;5414.43148469411;5423.322084739408;5428.75570599323;3921.784301863396;4115.444415003981;4130.359794231891;4188.916221675565;4194.07072746537;3886.348301971665;4129.15352352767;4176.937345939813;4182.832686692529;4191.308434108384;3458.515807786912;3627.0957747182674;4029.37544680575;4059.330210515276;4079.6537142742973;3438.0373495421322;3525.612182659931;3793.715471339661;3848.2880667076543;4103.815328310204;3056;3241.416545021895;3560.643935606419;3576.856925722968;3587.8744243951596;4362.738235705868;4469.1061787642275;4618.630509559227;5005.523370605643;5194.454582268883;4684.882877928045;4724.850193162103;4746.264329028972;4765.880852978668;4773.761311934424;4657.301404789275;4756.571908528249;4770.4019153583595;4869.711235719206;5021.995600879802;3144.3554071415306;3781.867634632802;3861.227754449121;3879.301725689375;3920.4315374270573;6036.89100113442;6042.607230530046;6050.757969681207;6052.4552790248645;6108.847278818021;3936.645701886844;3957.9802100989887;3959.940600890989;3960.485136932238;3965.1673148638533;3868.3423817951016;3992.744099583726;4009.639132478091;4035;4035.8183881725395;3904.668103211202;4032.5684382044537;4567.543245675406;4644.281615802175;4732.479427262998;4554.544545545419;4835.646019114653;5871.266091738533;5888.587798854125;6000.819975400657;1056.4242487844144;1160.6982184631888;1310.7706151423463;1349.6625843539116;1350.4065203060484;751.706834334599;780.8282177920848;832.8673004713585;894.2264940826138;929.7675581104724;4533.52397998212;4736.715797052188;4776.065693394181;5431.728413579384;5447.5641948644015;4970.975145124324;5620.91568626471;5834.445727655866;5880.382559831156;5880.8235752927485;5105.463926287761;5182.144946083854;5314.74123497512;5332.946670533245;5470.234167216593;2376.8692721900115;2394.844335118212;2448.013423623177;2624.3439140214946;2648.9801426450954;5033;5037.823676171316;5195.428502864682;5223.582113430993;5247.921281180786;4482.551744825491;4905.533974272438;5061.772220250024;5149.2791009258735;6161.568690191733;4887.780049897692;5873.353931067579;6163.781038826019;6244.968775156095;6321.1958911226875;5289.127293996447;5982.162497250301;5995.481897603105;6016.954235559756;6037.366076562073;4867.732274724783;5080.872978175576;5586.899270904687;5966.731497082728;5979.774146300085;6199.380061993765;6216.35266654346;6248.468880145057;6265.467435268053;6361.363863613787;3651.8721844735305;3817.8472861085247;3922.8038598070552;3982.980085099556;3997.9205614483344;4892.339534162842;4995.775190116527;5130.846074617767;5603.91974707447;5938.970305148447;4765;4827.927581086216;5368.57051435892;5682.034054210877;5778.191053252464;4347.826086956512;5015.648904576645;5659.490645841894;5873.327635925558;5879.735411906389;5408.23603928012;5595.440455954535;5647.616333998197;5752.942470575408;5795.449432303924;5285.4714528548375;5580.832575022756;5717.371089180104;5778.971105144447;5854.151148083589;5675.517581005661;5686.635207550241;5693.743781529758;5749.942500574941;5787 22:36:34 INFO - PROCESS | 1938 | |5;yui.html;612.534473799912;724.4856152131986;786.1823703348518;790.9920900790991;805.3355981315416;14492.63768405789;15278.847211527882;15429.382824687;15453.768193477106;15503.457378991723;10564.049235568797;13300.401481933312;14077.084989475678;14125.364358603862;14209.289535523209;710.7761055267574;732.059303794624;879.6217626420637;905.2305540290766;916.3585490156884;885.2961895293257;895.9462432254086;920.2453987730064;921.0789210789211;922.3267015078997;806.2904643913348;978.5400861595039;978.9755256118583;979.6179489998871;980.7842274699555;783.1933108897845;953.5708930981051;973.255459573427;973.547300505265;978.432509144698;726.2555880222814;729.5039373226205;731.8060713910818;732.8754111801006;735.4704612678863;14992.925035374754;15064;15198.544043678707;15326.693466130726;15457.613559660986;14368.20974846383;14742.778858317133;14775.852241477554;14787.260636968216;14892;11750.529978800838;11801.055915526736;11845.17083804134;11885.584004559885;11986;3794.430835374716;3811.8856434307013;3813.809309534512;3821.48409964654;3967.1867267210155;70634;94760;98747;99227.50386248095;99353.0129397408;8961.372703910663;11071;11129.332240065622;11227.550897964153;11248.493817778219;22878.885605571864;22885.199018034287;23126.65310020351;23157;23317.18389856381;7676.462647614725;7696.884546731803;7801.609919504002;7951.244631759973;8015.519068855887;4353.934690979638;4370.169667763178;4374.4531933508315;4473.709208901476;4550.795214215302;3361.680640339213;3445.8621655133516;3448.4827275908815;3657.5610926688437;3814.1799513104816;618.5762752514541;687.3161204601454;695.4818660098158;709.0711168369453;718.3498933465222;574.9732194016043;575.6315957787019;629.156929714176;662.3078882567728;716.3588588213623;8761.561921904007;9169.358144929924;9272.30457715674;9288.767780805534;9325.860112098324;646.4989633034342;659.3472462262422;673.5117040145855;680.1979118122424;699.618707804248;4255.9347144004205;4413.470383553929;4619.491855895849;4672.369230153954;4725.952740472551;83280.58359708163;89846;96617;108145;113073.43463282795;95758.0424195763;98779.65254343206;99640.50179749055;101758.49120754348;101943;71901.28098718946;73915.15212119633;76795.23204767992;79179;85121.14878851132;9628.229741620793;9709.6601618943;9857.457839818879;9872.555734991798;9957.751056223362;49336.01327973492;50644.24033639572;53051.55164914268;55332;58032.4196758027;63970;74444.25555744373;74608.25391746231;77849.61075194587;79242;12310.155853714417;12436.067294953096;12880.484780608671;12927.418266177856;12944.741105177656;14092.78860817068;14261.42954281817;14369.353379098175;14678.266086695408;14711.485098021518;12934.353282336035;12995.610131696065;13299.66750831237;13444.39500222512;13736.244506552235;13106.344682766014;13433.798493022809;13490.460381584626;13493;13662.453501860213;12947.223166610034;12947.223166610034;13240.602781916557;13243.404046817725;13422.530211442552;8573.65705371803;10988.450577470934;11129.387883666475;11339.829902551302;11476.483558239732;8224.465409748347;10055.346402483816;10996.175286853679;11213.327200368005;11263.549458021915 22:36:34 INFO - PROCESS | 1938 | __end_tp_report 22:36:34 INFO - PROCESS | 1938 | __start_cc_report 22:36:34 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,358 22:36:34 INFO - PROCESS | 1938 | __end_cc_report 22:36:34 INFO - PROCESS | 1938 | __startTimestamp1477632994062__endTimestamp 22:36:34 INFO - PROCESS | 1938 | 22:36:34 INFO - PROCESS | 1938 | ------- Summary: start ------- 22:36:34 INFO - PROCESS | 1938 | Number of tests: 6 22:36:34 INFO - PROCESS | 1938 | 22:36:34 INFO - PROCESS | 1938 | [#0] dojo.html Cycles:165 Average:5691.41 Median:5948.52 stddev:678.75 (11.4%) stddev-sans-first:644.95 22:36:34 INFO - PROCESS | 1938 | Values: 2915.3 3268.5 3385.2 3391.2 3427.2 4592.4 5593.7 5631.2 5676.8 5760.9 5613.0 5629.6 5753.2 5763.6 5813.0 5135.2 5181.6 5233.3 5272.4 5297.4 5179.2 5243.4 5287.1 5291.2 5365.1 5265.2 5277.5 5296.5 5296.9 5328.3 5097.2 5148.2 5171.8 5200.7 5217.2 5008.3 5108.0 5146.4 5227.5 5271.5 4020.2 5176.0 5276.3 5742.9 5779.7 5422.5 6164.9 6190.8 6237.2 6279.1 6180.7 6228.6 6230.3 6238.6 6245.2 5948.5 5973.8 5988.7 5990.0 6019.2 6165.7 6219.1 6227.0 6246.9 6396.8 6171.2 6175.5 6176.7 6181.5 6200.3 6246.4 6254.3 6259.4 6265.7 6266.6 6072.5 6105.5 6151.8 6216.2 6240.8 6125.9 6173.4 6215.4 6221.2 6264.1 4180.0 4951.6 4963.0 5002.0 5020.5 4456.2 4459.0 4506.9 4509.8 4526.3 5659.7 5666.3 5810.4 5838.9 5877.9 6190.8 6260.1 6268.2 6279.0 6280.6 5885.3 5911.6 5913.7 5915.9 5979.7 5238.5 5286.3 5391.4 5399.6 5431.8 6425.0 6425.9 6440.3 6457.6 6515.6 6272.3 6272.4 6272.5 6277.9 6285.6 4789.3 5428.1 5602.9 5743.4 5922.4 4727.0 5016.7 6097.2 6143.9 6147.3 6133.9 6149.6 6187.6 6244.0 6300.5 5421.9 5816.6 6094.8 6162.4 6168.5 5985.9 6116.7 6120.5 6175.9 6185.6 5813.3 5847.6 5868.9 6005.7 6129.6 5959.8 6004.1 6100.8 6121.3 6160.6 6000.4 6004.4 6016.7 6039.4 6086.7 22:36:34 INFO - PROCESS | 1938 | 22:36:34 INFO - PROCESS | 1938 | [#1] ext.html Cycles:180 Average:69930.62 Median:40414.90 stddev:152606.20 (377.6%) stddev-sans-first:152945.19 22:36:34 INFO - PROCESS | 1938 | Values: 470.2 511.0 524.9 528.0 577.2 37340.8 39176.4 40505.0 40668.6 40706.6 41405.0 41941.0 41992.0 42131.2 42196.2 9393.9 9509.3 9589.7 9592.6 9628.2 9422.9 9447.5 9484.6 9509.3 9525.6 9481.4 9530.9 9533.5 9548.6 9564.1 9448.7 9487.6 9495.4 9509.9 9537.8 7986.4 9417.2 9446.2 9465.5 9504.6 39372.8 39496.4 39595.6 39700.0 39772.6 3325.3 3623.0 3890.3 3897.5 4164.5 30370.8 31581.1 31602.5 31767.5 31795.4 3205.0 3308.0 3327.8 3356.8 3375.2 398093.0 501875.0 506133.0 507224.0 507491.0 48090.5 61165.7 61216.8 61515.0 61884.4 52886.0 55168.0 56286.0 65099.0 65159.0 15993.4 16101.3 16297.3 16331.0 16382.3 12413.8 12427.1 12517.2 12528.6 12570.3 15846.6 17044.4 17286.9 17363.6 17602.0 7419.4 7466.4 7508.8 7522.8 7556.5 832.6 845.6 934.0 973.3 1062.1 25504.6 26556.6 26576.6 30186.0 31130.0 1055.0 1079.5 1122.9 1138.0 1144.9 15302.2 15455.3 15551.4 15851.4 16123.3 638937.0 642071.0 644093.0 645840.0 650053.0 59829.7 59989.8 60080.1 60178.7 60194.0 1369.0 1384.0 1387.3 1406.2 1414.4 29390.7 30422.9 30568.4 30778.2 31019.5 51425.2 51979.5 52085.7 52170.7 52356.7 479878.0 589105.0 591937.0 593217.0 594601.0 22296.9 26600.7 26832.2 27345.5 27353.7 47756.0 48258.0 48336.8 48889.5 49314.8 39638.4 39781.2 40317.5 40793.2 41098.4 38136.0 39419.8 39757.6 40275.0 40324.8 38321.8 38889.0 39006.6 39030.0 39162.8 32798.0 34357.9 34548.3 34846.5 35080.5 21180.6 21626.0 24024.4 25825.0 25970.5 22:36:34 INFO - PROCESS | 1938 | 22:36:34 INFO - PROCESS | 1938 | [#2] jquery.html Cycles:180 Average:35096.47 Median:2248.36 stddev:99100.46 (4407.7%) stddev-sans-first:99344.16 22:36:34 INFO - PROCESS | 1938 | Values: 371.9 380.6 384.4 387.2 402.8 18992.0 20146.3 21607.9 21822.7 21919.0 20026.5 20296.6 20776.6 20792.8 21216.0 5120.2 5249.7 5907.2 6009.1 6009.4 3799.2 4015.7 5822.3 5930.3 5944.9 6210.3 6536.1 6580.3 6625.2 6625.2 6355.6 6514.9 6572.5 6581.9 6623.8 5494.2 5751.3 5814.5 5824.1 5827.1 18804.0 20845.9 25393.9 25546.5 26039.5 1756.6 2218.2 2278.5 2387.8 2637.8 10025.9 10074.5 10354.3 10444.1 14964.2 1766.0 1801.8 1858.8 1865.3 1901.2 126484.0 129703.0 132663.0 132782.3 133750.0 9265.8 11478.7 11682.5 11709.8 11799.2 12538.3 14091.9 14668.2 14759.6 14827.0 8247.8 8482.2 8532.5 8663.8 8689.1 8350.8 8380.2 8386.5 8446.9 8509.0 4577.8 4581.4 4604.4 4612.6 4645.8 1598.6 1683.0 1904.0 1980.9 1988.1 799.9 960.7 1003.0 1015.7 1064.8 18613.8 20607.4 20965.4 21046.6 21143.3 932.2 948.5 980.6 1009.7 1010.2 8739.1 10821.8 10987.2 11053.4 11090.3 585878.0 585968.0 589644.1 593327.6 603066.0 106191.5 126211.0 130451.0 130709.0 132673.0 991.9 997.6 1010.2 1024.1 1027.4 18200.9 18247.0 18583.8 18712.9 18802.0 56293.7 56341.0 56405.6 56924.1 57139.7 58551.7 79864.4 80383.8 81152.6 81483.0 7216.0 7288.8 7370.9 7438.1 7527.8 9586.4 10371.5 10518.7 12522.1 12894.4 8771.7 10037.2 11470.9 11526.8 11675.9 9036.6 11322.8 11360.8 11429.4 11769.9 11254.9 11473.2 11484.9 11686.5 11930.6 8106.6 8145.3 8406.0 8487.1 8591.4 9852.2 11480.5 11608.7 11676.0 11692.9 22:36:34 INFO - PROCESS | 1938 | 22:36:34 INFO - PROCESS | 1938 | [#3] mootools.html Cycles:180 Average:5520.35 Median:5087.85 stddev:3045.59 (59.9%) stddev-sans-first:3039.61 22:36:34 INFO - PROCESS | 1938 | Values: 1563.5 1594.5 1869.2 1919.4 1963.0 7657.7 7952.4 8195.5 8692.6 8697.7 6921.9 7640.7 7953.4 8581.0 8605.6 2165.5 2336.8 2339.0 2349.6 2349.7 2319.8 2347.0 2367.8 2374.8 2386.6 1808.3 2109.9 2312.1 2314.2 2336.8 2301.4 2304.4 2329.3 2331.4 2333.4 1928.6 2330.8 2353.7 2355.0 2360.0 8546.3 8612.0 8646.9 8661.8 8698.2 5200.3 5364.7 5371.6 5389.9 5412.9 3311.0 3385.3 3647.8 3657.5 3657.7 927.9 985.6 1057.9 1071.5 1077.5 11080.7 11083.2 11148.9 11178.8 11194.6 4532.6 4605.7 4675.2 4687.9 4800.2 9576.1 9639.1 9640.2 9663.1 9698.3 5313.6 5338.0 5339.5 5341.7 5387.4 4036.3 4372.9 4447.2 4882.7 4901.6 3814.6 3872.7 3907.2 3955.3 3976.2 1264.2 1273.5 1278.6 1286.7 1289.1 842.4 913.8 924.5 935.6 952.4 5655.2 5836.9 5875.0 5910.7 5922.0 426.3 433.9 436.7 449.3 452.3 3884.5 3924.0 3952.7 3986.9 3998.5 8565.6 9959.6 10314.8 10352.3 10361.5 10159.8 10299.4 10317.6 10333.7 10335.2 7644.0 7980.8 8016.6 8114.5 8117.7 4773.1 5623.9 5879.4 5942.5 6002.2 8947.7 9326.4 9369.3 9408.5 9417.6 9423.5 9459.9 9654.7 9716.4 9727.2 6696.4 6885.3 7126.1 7237.7 7408.4 7921.9 8057.8 8146.1 8164.0 8174.3 7191.7 7338.3 7353.0 7393.9 7449.6 7022.2 7102.4 7168.0 7179.2 7327.9 6737.5 6876.5 6901.3 6911.3 6916.2 4810.3 4814.9 4924.9 4998.3 5007.6 4880.7 4968.0 5014.2 5073.4 5102.3 22:36:34 INFO - PROCESS | 1938 | 22:36:34 INFO - PROCESS | 1938 | [#4] prototype.html Cycles:180 Average:4526.60 Median:4919.96 stddev:1440.05 (29.3%) stddev-sans-first:1408.73 22:36:34 INFO - PROCESS | 1938 | Values: 300.7 305.3 311.3 314.5 318.7 4497.4 4934.4 5122.2 5478.8 5601.8 5192.1 5331.3 5414.4 5423.3 5428.8 3921.8 4115.4 4130.4 4188.9 4194.1 3886.3 4129.2 4176.9 4182.8 4191.3 3458.5 3627.1 4029.4 4059.3 4079.7 3438.0 3525.6 3793.7 3848.3 4103.8 3056.0 3241.4 3560.6 3576.9 3587.9 4362.7 4469.1 4618.6 5005.5 5194.5 4684.9 4724.9 4746.3 4765.9 4773.8 4657.3 4756.6 4770.4 4869.7 5022.0 3144.4 3781.9 3861.2 3879.3 3920.4 6036.9 6042.6 6050.8 6052.5 6108.8 3936.6 3958.0 3959.9 3960.5 3965.2 3868.3 3992.7 4009.6 4035.0 4035.8 3904.7 4032.6 4567.5 4644.3 4732.5 4554.5 4835.6 5871.3 5888.6 6000.8 1056.4 1160.7 1310.8 1349.7 1350.4 751.7 780.8 832.9 894.2 929.8 4533.5 4736.7 4776.1 5431.7 5447.6 4971.0 5620.9 5834.4 5880.4 5880.8 5105.5 5182.1 5314.7 5332.9 5470.2 2376.9 2394.8 2448.0 2624.3 2649.0 5033.0 5037.8 5195.4 5223.6 5247.9 4482.6 4905.5 5061.8 5149.3 6161.6 4887.8 5873.4 6163.8 6245.0 6321.2 5289.1 5982.2 5995.5 6017.0 6037.4 4867.7 5080.9 5586.9 5966.7 5979.8 6199.4 6216.4 6248.5 6265.5 6361.4 3651.9 3817.8 3922.8 3983.0 3997.9 4892.3 4995.8 5130.8 5603.9 5939.0 4765.0 4827.9 5368.6 5682.0 5778.2 4347.8 5015.6 5659.5 5873.3 5879.7 5408.2 5595.4 5647.6 5752.9 5795.4 5285.5 5580.8 5717.4 5779.0 5854.2 5675.5 5686.6 5693.7 5749.9 5787.0 22:36:34 INFO - PROCESS | 1938 | 22:36:34 INFO - PROCESS | 1938 | [#5] yui.html Cycles:180 Average:20481.64 Median:27030.98 stddev:29294.02 (108.4%) stddev-sans-first:29338.21 22:36:34 INFO - PROCESS | 1938 | Values: 612.5 724.5 786.2 791.0 805.3 14492.6 15278.8 15429.4 15453.8 15503.5 10564.0 13300.4 14077.1 14125.4 14209.3 710.8 732.1 879.6 905.2 916.4 885.3 895.9 920.2 921.1 922.3 806.3 978.5 979.0 979.6 980.8 783.2 953.6 973.3 973.5 978.4 726.3 729.5 731.8 732.9 735.5 14992.9 15064.0 15198.5 15326.7 15457.6 14368.2 14742.8 14775.9 14787.3 14892.0 11750.5 11801.1 11845.2 11885.6 11986.0 3794.4 3811.9 3813.8 3821.5 3967.2 70634.0 94760.0 98747.0 99227.5 99353.0 8961.4 11071.0 11129.3 11227.6 11248.5 22878.9 22885.2 23126.7 23157.0 23317.2 7676.5 7696.9 7801.6 7951.2 8015.5 4353.9 4370.2 4374.5 4473.7 4550.8 3361.7 3445.9 3448.5 3657.6 3814.2 618.6 687.3 695.5 709.1 718.3 575.0 575.6 629.2 662.3 716.4 8761.6 9169.4 9272.3 9288.8 9325.9 646.5 659.3 673.5 680.2 699.6 4255.9 4413.5 4619.5 4672.4 4726.0 83280.6 89846.0 96617.0 108145.0 113073.4 95758.0 98779.7 99640.5 101758.5 101943.0 71901.3 73915.2 76795.2 79179.0 85121.1 9628.2 9709.7 9857.5 9872.6 9957.8 49336.0 50644.2 53051.6 55332.0 58032.4 63970.0 74444.3 74608.3 77849.6 79242.0 12310.2 12436.1 12880.5 12927.4 12944.7 14092.8 14261.4 14369.4 14678.3 14711.5 12934.4 12995.6 13299.7 13444.4 13736.2 13106.3 13433.8 13490.5 13493.0 13662.5 12947.2 12947.2 13240.6 13243.4 13422.5 8573.7 10988.5 11129.4 11339.8 11476.5 8224.5 10055.3 10996.2 11213.3 11263.5 22:36:34 INFO - PROCESS | 1938 | -------- Summary: end -------- 22:36:34 INFO - PROCESS | 1938 | 22:36:34 INFO - TEST-INFO | 1938: exit 0 22:36:34 INFO - TEST-OK | dromaeo_css | took 1115339ms 22:36:34 INFO - TEST-START | kraken 22:36:34 INFO - operating with platform_type : mac_ 22:36:34 INFO - Initialising browser for kraken test... 22:36:34 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmpddnSTl/profile http://localhost:51318/getInfo.html) 22:36:37 INFO - PROCESS | 1943 | 2016-10-27 22:36:37.345 plugin-container[1944:16028] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 22:36:37 INFO - PROCESS | 1943 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:36:37 INFO - PROCESS | 1943 | 2016-10-27 22:36:37.348 plugin-container[1944:16028] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.CFPasteboardClient' 22:36:37 INFO - PROCESS | 1943 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:36:37 INFO - PROCESS | 1943 | 2016-10-27 22:36:37.348 plugin-container[1944:16028] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:36:37 INFO - PROCESS | 1943 | __metrics Screen width/height:1600/1200 22:36:37 INFO - PROCESS | 1943 | colorDepth:24 22:36:37 INFO - PROCESS | 1943 | Browser inner width/height: 1024/689 22:36:37 INFO - PROCESS | 1943 | __metrics 22:36:37 ERROR - PROCESS | 1943 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 22:36:38 INFO - TEST-INFO | 1943: exit 0 22:36:38 INFO - Browser initialized. 22:36:38 INFO - Running cycle 1/1 for kraken test... 22:36:38 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmpddnSTl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:36:40 INFO - PROCESS | 1945 | 2016-10-27 22:36:40.446 plugin-container[1946:16148] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 22:36:40 INFO - PROCESS | 1945 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:36:40 INFO - PROCESS | 1945 | 2016-10-27 22:36:40.446 plugin-container[1946:16148] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4723, name = 'com.apple.CFPasteboardClient' 22:36:40 INFO - PROCESS | 1945 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:36:40 INFO - PROCESS | 1945 | 2016-10-27 22:36:40.447 plugin-container[1946:16148] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:36:50 INFO - PROCESS | 1945 | RSS: Main: 236576768 22:36:50 INFO - PROCESS | 1945 | 22:36:53 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51318/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51318/tests/kraken/driver.html?testName=audio-beat-detection) 22:36:54 INFO - PROCESS | 1945 | RSS: Main: 249114624 22:36:54 INFO - PROCESS | 1945 | 22:36:56 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51318/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51318/tests/kraken/driver.html?testName=audio-dft) 22:36:57 INFO - PROCESS | 1945 | RSS: Main: 257441792 22:36:57 INFO - PROCESS | 1945 | 22:37:00 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51318/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51318/tests/kraken/driver.html?testName=audio-fft) 22:37:01 INFO - PROCESS | 1945 | RSS: Main: 259956736 22:37:01 INFO - PROCESS | 1945 | 22:37:03 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51318/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51318/tests/kraken/driver.html?testName=audio-oscillator) 22:37:04 INFO - PROCESS | 1945 | RSS: Main: 260784128 22:37:04 INFO - PROCESS | 1945 | 22:37:06 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51318/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51318/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:37:06 INFO - PROCESS | 1945 | RSS: Main: 261111808 22:37:06 INFO - PROCESS | 1945 | 22:37:09 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51318/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51318/tests/kraken/driver.html?testName=imaging-darkroom) 22:37:10 INFO - PROCESS | 1945 | RSS: Main: 261455872 22:37:10 INFO - PROCESS | 1945 | 22:37:13 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51318/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51318/tests/kraken/driver.html?testName=imaging-desaturate) 22:37:14 INFO - PROCESS | 1945 | RSS: Main: 260788224 22:37:14 INFO - PROCESS | 1945 | 22:37:17 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51318/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51318/tests/kraken/driver.html?testName=json-parse-financial) 22:37:17 INFO - PROCESS | 1945 | RSS: Main: 261988352 22:37:17 INFO - PROCESS | 1945 | 22:37:19 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51318/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51318/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:37:20 INFO - PROCESS | 1945 | RSS: Main: 262123520 22:37:20 INFO - PROCESS | 1945 | 22:37:22 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51318/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51318/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:37:22 INFO - PROCESS | 1945 | RSS: Main: 262242304 22:37:22 INFO - PROCESS | 1945 | 22:37:25 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51318/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51318/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:37:25 INFO - PROCESS | 1945 | RSS: Main: 262307840 22:37:25 INFO - PROCESS | 1945 | 22:37:28 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51318/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51318/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:37:29 INFO - PROCESS | 1945 | RSS: Main: 262590464 22:37:29 INFO - PROCESS | 1945 | 22:37:32 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51318/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51318/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:37:33 INFO - PROCESS | 1945 | RSS: Main: 262393856 22:37:33 INFO - PROCESS | 1945 | 22:37:35 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51318/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51318/tests/kraken/driver.html?testName=ai-astar) 22:37:35 INFO - PROCESS | 1945 | RSS: Main: 264114176 22:37:35 INFO - PROCESS | 1945 | 22:37:35 INFO - PROCESS | 1945 | __start_tp_report 22:37:35 INFO - PROCESS | 1945 | _x_x_mozilla_page_load 22:37:35 INFO - PROCESS | 1945 | _x_x_mozilla_page_load_details 22:37:35 INFO - PROCESS | 1945 | |i|pagename|runs| 22:37:35 INFO - PROCESS | 1945 | |0;ai-astar;108;125;143;109;103;97;98;111;152;174 22:37:35 INFO - PROCESS | 1945 | |1;audio-beat-detection;178;120;158;139;108;137;116;114;143;114 22:37:35 INFO - PROCESS | 1945 | |2;audio-dft;201;213;218;205;255;198;267;279;193;203 22:37:35 INFO - PROCESS | 1945 | |3;audio-fft;83;59;58;80;75;52;58;69;106;81 22:37:35 INFO - PROCESS | 1945 | |4;audio-oscillator;87;93;87;72;70;71;89;69;72;85 22:37:35 INFO - PROCESS | 1945 | |5;imaging-gaussian-blur;89;87;84;85;90;86;90;87;88;88 22:37:35 INFO - PROCESS | 1945 | |6;imaging-darkroom;175;139;109;176;175;176;139;174;175;99 22:37:35 INFO - PROCESS | 1945 | |7;imaging-desaturate;143;141;86;87;88;126;116;89;88;90 22:37:35 INFO - PROCESS | 1945 | |8;json-parse-financial;55;59;93;88;57;58;59;59;58;57 22:37:35 INFO - PROCESS | 1945 | |9;json-stringify-tinderbox;68;75;88;101;70;89;61;72;109;79 22:37:35 INFO - PROCESS | 1945 | |10;stanford-crypto-aes;116;117;88;87;89;88;88;103;113;114 22:37:35 INFO - PROCESS | 1945 | |11;stanford-crypto-ccm;148;162;161;169;157;167;165;164;219;151 22:37:35 INFO - PROCESS | 1945 | |12;stanford-crypto-pbkdf2;177;180;177;246;201;175;174;173;173;176 22:37:35 INFO - PROCESS | 1945 | |13;stanford-crypto-sha256-iterative;68;72;77;91;93;88;71;73;68;83 22:37:35 INFO - PROCESS | 1945 | __end_tp_report 22:37:35 INFO - PROCESS | 1945 | __start_cc_report 22:37:35 INFO - PROCESS | 1945 | _x_x_mozilla_cycle_collect,931 22:37:35 INFO - PROCESS | 1945 | __end_cc_report 22:37:35 INFO - PROCESS | 1945 | __startTimestamp1477633055289__endTimestamp 22:37:35 INFO - PROCESS | 1945 | 22:37:35 INFO - PROCESS | 1945 | ------- Summary: start ------- 22:37:35 INFO - PROCESS | 1945 | Number of tests: 14 22:37:35 INFO - PROCESS | 1945 | 22:37:35 INFO - PROCESS | 1945 | [#0] ai-astar Cycles:10 Average:122.00 Median:147.50 stddev:26.04 (17.7%) stddev-sans-first:27.12 22:37:35 INFO - PROCESS | 1945 | Values: 108.0 125.0 143.0 109.0 103.0 97.0 98.0 111.0 152.0 174.0 22:37:35 INFO - PROCESS | 1945 | 22:37:35 INFO - PROCESS | 1945 | [#1] audio-beat-detection Cycles:10 Average:132.70 Median:138.00 stddev:22.62 (16.4%) stddev-sans-first:17.05 22:37:35 INFO - PROCESS | 1945 | Values: 178.0 120.0 158.0 139.0 108.0 137.0 116.0 114.0 143.0 114.0 22:37:35 INFO - PROCESS | 1945 | 22:37:35 INFO - PROCESS | 1945 | [#2] audio-dft Cycles:10 Average:223.20 Median:215.50 stddev:31.54 (14.6%) stddev-sans-first:32.42 22:37:35 INFO - PROCESS | 1945 | Values: 201.0 213.0 218.0 205.0 255.0 198.0 267.0 279.0 193.0 203.0 22:37:35 INFO - PROCESS | 1945 | 22:37:35 INFO - PROCESS | 1945 | [#3] audio-fft Cycles:10 Average:72.10 Median:72.00 stddev:16.33 (22.7%) stddev-sans-first:16.84 22:37:35 INFO - PROCESS | 1945 | Values: 83.0 59.0 58.0 80.0 75.0 52.0 58.0 69.0 106.0 81.0 22:37:35 INFO - PROCESS | 1945 | 22:37:35 INFO - PROCESS | 1945 | [#4] audio-oscillator Cycles:10 Average:79.50 Median:86.00 stddev:9.43 (11.0%) stddev-sans-first:9.60 22:37:35 INFO - PROCESS | 1945 | Values: 87.0 93.0 87.0 72.0 70.0 71.0 89.0 69.0 72.0 85.0 22:37:35 INFO - PROCESS | 1945 | 22:37:35 INFO - PROCESS | 1945 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.00 stddev:2.01 (2.3%) stddev-sans-first:2.05 22:37:35 INFO - PROCESS | 1945 | Values: 89.0 87.0 84.0 85.0 90.0 86.0 90.0 87.0 88.0 88.0 22:37:35 INFO - PROCESS | 1945 | 22:37:35 INFO - PROCESS | 1945 | [#6] imaging-darkroom Cycles:10 Average:153.70 Median:175.00 stddev:30.17 (17.2%) stddev-sans-first:31.00 22:37:35 INFO - PROCESS | 1945 | Values: 175.0 139.0 109.0 176.0 175.0 176.0 139.0 174.0 175.0 99.0 22:37:35 INFO - PROCESS | 1945 | 22:37:35 INFO - PROCESS | 1945 | [#7] imaging-desaturate Cycles:10 Average:105.40 Median:87.50 stddev:23.67 (27.1%) stddev-sans-first:20.84 22:37:35 INFO - PROCESS | 1945 | Values: 143.0 141.0 86.0 87.0 88.0 126.0 116.0 89.0 88.0 90.0 22:37:35 INFO - PROCESS | 1945 | 22:37:35 INFO - PROCESS | 1945 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:59.00 stddev:13.91 (23.6%) stddev-sans-first:14.34 22:37:35 INFO - PROCESS | 1945 | Values: 55.0 59.0 93.0 88.0 57.0 58.0 59.0 59.0 58.0 57.0 22:37:35 INFO - PROCESS | 1945 | 22:37:35 INFO - PROCESS | 1945 | [#9] json-stringify-tinderbox Cycles:10 Average:81.20 Median:73.50 stddev:15.30 (20.8%) stddev-sans-first:15.47 22:37:35 INFO - PROCESS | 1945 | Values: 68.0 75.0 88.0 101.0 70.0 89.0 61.0 72.0 109.0 79.0 22:37:35 INFO - PROCESS | 1945 | 22:37:35 INFO - PROCESS | 1945 | [#10] stanford-crypto-aes Cycles:10 Average:100.30 Median:87.50 stddev:13.50 (15.4%) stddev-sans-first:13.07 22:37:35 INFO - PROCESS | 1945 | Values: 116.0 117.0 88.0 87.0 89.0 88.0 88.0 103.0 113.0 114.0 22:37:35 INFO - PROCESS | 1945 | 22:37:35 INFO - PROCESS | 1945 | [#11] stanford-crypto-ccm Cycles:10 Average:166.30 Median:164.50 stddev:19.70 (12.0%) stddev-sans-first:19.75 22:37:35 INFO - PROCESS | 1945 | Values: 148.0 162.0 161.0 169.0 157.0 167.0 165.0 164.0 219.0 151.0 22:37:35 INFO - PROCESS | 1945 | 22:37:35 INFO - PROCESS | 1945 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.20 Median:177.00 stddev:22.90 (12.9%) stddev-sans-first:24.10 22:37:35 INFO - PROCESS | 1945 | Values: 177.0 180.0 177.0 246.0 201.0 175.0 174.0 173.0 173.0 176.0 22:37:35 INFO - PROCESS | 1945 | 22:37:35 INFO - PROCESS | 1945 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.40 Median:80.00 stddev:9.59 (12.0%) stddev-sans-first:9.41 22:37:35 INFO - PROCESS | 1945 | Values: 68.0 72.0 77.0 91.0 93.0 88.0 71.0 73.0 68.0 83.0 22:37:35 INFO - PROCESS | 1945 | -------- Summary: end -------- 22:37:35 INFO - PROCESS | 1945 | 22:37:36 INFO - TEST-INFO | 1945: exit 0 22:37:36 INFO - TEST-OK | kraken | took 61434ms 22:37:36 INFO - SUITE-END | took 1176s 22:37:36 INFO - Completed test suite (00:19:37) 22:37:36 INFO - javascript benchmark 22:37:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7247.698203364337, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2915.2566095645607, 3268.477043673012, 3385.22139907821, 3391.203067279189, 3427.1774774054234, 4592.448906131261, 5593.74828132734, 5631.239782629344, 5676.801311954087, 5760.889119875263, 5613.045782217023, 5629.60592758506, 5753.165790960308, 5763.625364351315, 5813.040848260032, 5135.204043373283, 5181.559567436753, 5233.267342572043, 5272.393674727398, 5297.364316282052, 5179.171332586787, 5243.4232234454175, 5287.101192797234, 5291.2327712481665, 5365.141577347626, 5265.18389649603, 5277.47225277473, 5296.496832800878, 5296.92054619181, 5328.307320048389, 5097.184450487941, 5148.176291793295, 5171.81898633546, 5200.739963001834, 5217.191335343028, 5008.348914641109, 5107.9744601277125, 5146.408163061258, 5227.451117632631, 5271.499207575274, 4020.155767288873, 5175.974120129413, 5276.3140791697215, 5742.913856292159, 5779.739911703983, 5422.457754224585, 6164.907526387108, 6190.81427557174, 6237.1891654084675, 6279.089532017878, 6180.690965451709, 6228.5951413158, 6230.283517395467, 6238.5944913580925, 6245.156903817971, 5948.524118070587, 5973.820785376446, 5988.730507127189, 5989.970050149721, 6019.217501724747, 6165.691715414211, 6219.067139929047, 6227.0, 6246.937530624636, 6396.808095757135, 6171.197744293213, 6175.536834737412, 6176.722047507873, 6181.474574661204, 6200.286966998763, 6246.437820596168, 6254.312025677172, 6259.436650701458, 6265.71804268809, 6266.624002559861, 6072.453479186895, 6105.450509454082, 6151.7539298428455, 6216.160818289563, 6240.781572644935, 6125.938740612537, 6173.382661733881, 6215.378462153749, 6221.191245138103, 6264.060390941395, 4179.97910010448, 4951.628627852925, 4962.950370496249, 5002.0, 5020.548150666457, 4456.242438785414, 4458.977705111453, 4506.909861802746, 4509.819607215741, 4526.275795872644, 5659.688717120515, 5666.291713535808, 5810.4189581041555, 5838.9124163137585, 5877.94122058774, 6190.7523699052435, 6260.060990851409, 6268.216472940882, 6279.0, 6280.560360774702, 5885.323187833454, 5911.556633252523, 5913.704314784243, 5915.911261331084, 5979.701014949235, 5238.476152384807, 5286.312779338661, 5391.353037635509, 5399.595030372738, 5431.782728690808, 6425.004124360731, 6425.903611445926, 6440.25937017249, 6457.61254324742, 6515.576487528296, 6272.278687950944, 6272.40412160844, 6272.466840318624, 6277.869983403031, 6285.622862628257, 4789.329493870791, 5428.104362780097, 5602.859928501657, 5743.368229494668, 5922.407759224043, 4727.0, 5016.673916195436, 6097.222222222223, 6143.880361275353, 6147.262328520606, 6133.885575987568, 6149.569530133027, 6187.597806142587, 6243.96878015607, 6300.495960323072, 5421.864453388697, 5816.6330912236435, 6094.78668246627, 6162.352952940056, 6168.506519478521, 5985.880282394415, 6116.724747386467, 6120.479759220516, 6175.938240617536, 6185.567010309415, 5813.273340832396, 5847.561432892465, 5868.911966320423, 6005.729742161526, 6129.570930034854, 5959.76160953557, 6004.1294012368835, 6100.786472473442, 6121.326654068138, 6160.630362178284, 6000.429959153874, 6004.42957918997, 6016.699165041818, 6039.4262545058145, 6086.6652334122045], "value": 5648.438407376115, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.20535295350857, 510.9576464013408, 524.9186376111702, 527.9920801187983, 577.2178697864391, 37340.81329593352, 39176.41235381472, 40505.0, 40668.59331406685, 40706.59293407065, 41405.0, 41941.0, 41992.0, 42131.15737685252, 42196.15607687845, 9393.859092113607, 9509.286803489731, 9589.712308630717, 9592.568334424968, 9628.18160456362, 9422.905770942272, 9447.527623618791, 9484.620615175385, 9509.286803489766, 9525.5713492893, 9481.383710058859, 9530.903946046225, 9533.523323833815, 9548.61805527778, 9564.091411315914, 9448.71653850382, 9487.620495180185, 9495.382800118039, 9509.857352139723, 9537.809243815154, 7986.440949133563, 9417.24662027043, 9446.244300455946, 9465.526723663857, 9504.572294246775, 39372.80313598413, 39496.40755388672, 39595.60404395948, 39700.0, 39772.60227397747, 3325.268750155873, 3623.0398944279787, 3890.2997460457104, 3897.493325867619, 4164.541900390955, 30370.84814575913, 31581.052568422983, 31602.525962110587, 31767.52348714771, 31795.364092718017, 3205.022468147214, 3307.96692033079, 3327.8336083195745, 3356.848941797625, 3375.1730825947557, 398093.0, 501875.0, 506133.0, 507224.0, 507491.0, 48090.5190948093, 61165.694171528856, 61216.775664486464, 61515.0, 61884.38115618876, 52886.0, 55168.034876861275, 56286.0, 65099.0, 65159.0, 15993.440229591908, 16101.27544260511, 16297.348106075624, 16331.0, 16382.262798174112, 12413.813792793115, 12427.130100892851, 12517.248965062125, 12528.624141275777, 12570.308633025272, 15846.60383490422, 17044.403445879336, 17286.913565432093, 17363.565910852078, 17602.03188824627, 7419.406447484188, 7466.440016998638, 7508.812279693051, 7522.811929701692, 7556.4710470266555, 832.5878690048464, 845.6067928413318, 933.9912894074383, 973.3089506450499, 1062.0919114157439, 25504.617430738555, 26556.60165097525, 26576.601350979752, 30186.0, 31130.0, 1055.0, 1079.4602698650674, 1122.93262404256, 1137.988620113805, 1144.891235332642, 15302.158381288913, 15455.30451129702, 15551.377944882304, 15851.445199417965, 16123.274452649659, 638937.0, 642071.0, 644093.0, 645840.0, 650053.0, 59829.700851495465, 59989.80020399568, 60080.098798518055, 60178.69910650419, 60194.0, 1368.9931550342185, 1383.9515616953358, 1387.3271463340272, 1406.2476575032308, 1414.3776738234938, 29390.706092939654, 30422.93519726799, 30568.388632227674, 30778.21361200097, 31019.534706979866, 51425.22862156996, 51979.48020519746, 52085.7395713019, 52170.739146304026, 52356.73821630867, 479878.0, 589105.0, 591937.0, 593217.0, 594601.0, 22296.88851555732, 26600.733992659825, 26832.19503414901, 27345.453090938467, 27353.726462735118, 47756.0, 48258.0, 48336.758316208194, 48889.511104888494, 49314.75342623264, 39638.405423918084, 39781.237189677246, 40317.476114648984, 40793.184136316515, 41098.38352424776, 38136.0, 39419.80290098531, 39757.60242397655, 40275.0, 40324.79837600793, 38321.80839095786, 38889.0, 39006.6099339003, 39030.0, 39162.80418598003, 32798.0, 34357.87705475359, 34548.30903381868, 34846.47730283997, 35080.47379289364, 21180.629477614202, 21626.0, 24024.39939001539, 25825.0, 25970.480590388463], "value": 19943.545137709905, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.9088823238307, 380.6250842919724, 384.42386216512296, 387.2004311097585, 402.794574766869, 18991.95544245066, 20146.294879679215, 21607.891960540193, 21822.672659910073, 21919.0, 20026.49933751657, 20296.594068118666, 20776.584468310662, 20792.792072079275, 21216.04527796249, 5120.231965205221, 5249.711265880375, 5907.172995780573, 6009.1286763419275, 6009.369016253274, 3799.164183879556, 4015.738976966488, 5822.301323841145, 5930.288365396159, 5944.940550594503, 6210.2547694276755, 6536.052272420497, 6580.276169621337, 6625.17185351831, 6625.211192977893, 6355.618662880219, 6514.869702605922, 6572.5399222054475, 6581.901271480884, 6623.801285961405, 5494.1758736189495, 5751.281089863767, 5814.534837213013, 5824.068149096124, 5827.096799996008, 18804.0, 20845.8534780587, 25393.873030634913, 25546.48907021868, 26039.479210415684, 1756.6311074674334, 2218.1792736687366, 2278.544291141778, 2387.78509934105, 2637.788976881845, 10025.949870250675, 10074.496275186284, 10354.326968747082, 10444.060034596847, 14964.176970266632, 1765.9911700441417, 1801.8378345948927, 1858.8327050565379, 1865.2818664814145, 1901.1729897494495, 126484.0, 129703.0, 132663.0, 132782.33608831893, 133750.0, 9265.7954465919, 11478.655640330804, 11682.474288657031, 11709.765804683859, 11799.233049851731, 12538.310392928477, 14091.929540352437, 14668.193249371387, 14759.557213283619, 14827.0, 8247.752567422987, 8482.151784821592, 8532.488050716976, 8663.826723465496, 8689.13108689126, 8350.832983340299, 8380.16198380157, 8386.454880432751, 8446.915530844735, 8509.0, 4577.793999270041, 4581.427321584802, 4604.401427814362, 4612.630989520831, 4645.790939407735, 1598.6482973745758, 1682.9831701683072, 1903.9714404283948, 1980.9306674266331, 1988.0656091637197, 799.8880156778056, 960.6973803251954, 1002.9749256268651, 1015.720676813882, 1064.776396956648, 18613.81386186148, 20607.381778546667, 20965.37103886886, 21046.579068418545, 21143.25998590042, 932.1889955738551, 948.4546385828038, 980.5930538826307, 1009.7071849163807, 1010.1968934696934, 8739.12848225368, 10821.783564328827, 10987.230893837355, 11053.392063436588, 11090.334579925231, 585878.0, 585968.0, 589644.0517797469, 593327.5671567878, 603066.0, 106191.46904265584, 126211.0, 130451.0, 130709.0, 132673.0099048515, 991.9305648604528, 997.6159178716101, 1010.2271762101998, 1024.08856118055, 1027.3733022856052, 18200.908995454938, 18247.0, 18583.81416185875, 18712.90643546828, 18802.0, 56293.71853140708, 56341.0, 56405.58986025218, 56924.14613780879, 57139.71430142823, 58551.70724146352, 79864.4027119466, 80383.79424308757, 81152.5942370308, 81483.0, 7216.0258365120035, 7288.781336559911, 7370.889436658562, 7438.144613369315, 7527.774166775005, 9586.376885502421, 10371.481425928523, 10518.684439466828, 12522.06084543681, 12894.419751111034, 8771.736847894574, 10037.19702423819, 11470.942645286721, 11526.827097593376, 11675.88324116748, 9036.593353298986, 11322.830157547478, 11360.772784544097, 11429.371384573928, 11769.882301177222, 11254.94372528132, 11473.196876218584, 11484.94257528707, 11686.532538698357, 11930.582429614922, 8106.635201415833, 8145.348372130334, 8406.0, 8487.066422693622, 8591.355648326275, 9852.211823054282, 11480.540778369106, 11608.709782255512, 11676.0, 11692.883071169179], "value": 9570.355489472988, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1563.4762354611205, 1594.4897632757516, 1869.2429566025755, 1919.4337670387235, 1962.9607407851843, 7657.731979380723, 7952.443328966975, 8195.508269503835, 8692.608832602533, 8697.695580654687, 6921.927101299307, 7640.656170472328, 7953.3695592403565, 8581.0, 8605.612747426365, 2165.545235500547, 2336.8247381446376, 2339.0, 2349.5888219561593, 2349.7297810751725, 2319.8492098013658, 2346.9765302347014, 2367.8342516023886, 2374.7625237476286, 2386.606209975361, 1808.2586139682733, 2109.945027486257, 2312.1213938703268, 2314.2247347138727, 2336.789688927996, 2301.355620426283, 2304.37781798915, 2329.3477826208773, 2331.4287999440244, 2333.381653861728, 1928.5660726336603, 2330.8484948478294, 2353.705786776653, 2355.0, 2359.988200059006, 8546.316294696471, 8611.956940215321, 8646.870296945488, 8661.783455413602, 8698.21716045559, 5200.323957885488, 5364.6512976656395, 5371.59713021521, 5389.892202155974, 5412.864678383033, 3310.9503357449657, 3385.339858727549, 3647.7993710345922, 3657.5062366580705, 3657.7439579229463, 927.8793756811639, 985.5515740338129, 1057.8783439904432, 1071.5017516854623, 1077.488193108275, 11080.667579972614, 11083.224174307801, 11148.944255278671, 11178.776424471465, 11194.552217911356, 4532.592066714012, 4605.654575906753, 4675.18184317743, 4687.882802929886, 4800.231962885921, 9576.138147566613, 9639.132478077006, 9640.180584650383, 9663.130318271249, 9698.321117521848, 5313.57491400687, 5338.0, 5339.466053394707, 5341.65279256847, 5387.434319396485, 4036.273470775289, 4372.890677733082, 4447.199504089295, 4882.707037577757, 4901.632377571696, 3814.6376094271, 3872.670822980022, 3907.159960608426, 3955.3078211312904, 3976.1848820991627, 1264.2414551269164, 1273.5287943460978, 1278.565287802133, 1286.7104901397297, 1289.1233960906486, 842.3850589070013, 913.794396260836, 924.4638109896244, 935.5649622925372, 952.3809523809441, 5655.236543066633, 5836.9124463133085, 5875.0, 5910.734016969247, 5921.96365636012, 426.30299460382497, 433.9067100573392, 436.73795722566206, 449.29909341427475, 452.2673269303683, 3884.475595794588, 3923.9803800981385, 3952.7233093683167, 3986.9202615948097, 3998.520177578709, 8565.571721413904, 9959.601615935282, 10314.845277320846, 10352.327098738559, 10361.513236823108, 10159.847602285972, 10299.433531155859, 10317.638882639072, 10333.74165645865, 10335.17318614509, 7644.0, 7980.84038319242, 8016.599170041358, 8114.513129212266, 8117.715879944291, 4773.116973359801, 5623.8594035149445, 5879.353271140257, 5942.524598032233, 6002.189704389852, 8947.686830960885, 9326.373104878096, 9369.297302702189, 9408.529573521433, 9417.623295068395, 9423.481708506099, 9459.858102128612, 9654.71035868925, 9716.368436051634, 9727.221822254343, 6696.363845434676, 6885.345892140239, 7126.073610430611, 7237.674304656198, 7408.370288525536, 7921.881171782313, 8057.83884322322, 8146.103928567734, 8163.959180204061, 8174.305184059422, 7191.71233150689, 7338.266173382619, 7353.0, 7393.8521229574035, 7449.553026818408, 7022.192447868356, 7102.431805455654, 7168.0, 7179.210286868545, 7327.926720732938, 6737.528373013842, 6876.484263680144, 6901.344372284625, 6911.30886911325, 6916.170059592881, 4810.278458231293, 4814.855554333376, 4924.852254432372, 4998.325226094431, 5007.649464537448, 4880.731559764247, 4967.975160124176, 5014.247862820606, 5073.365829271341, 5102.336696229467], "value": 4396.565646598468, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.693292841302, 305.252132275924, 311.29336406357567, 314.4701178514205, 318.6749515494196, 4497.370368148458, 4934.383202099738, 5122.154844450662, 5478.808241711541, 5601.815292036109, 5192.065428222918, 5331.280277162582, 5414.43148469411, 5423.322084739408, 5428.75570599323, 3921.784301863396, 4115.444415003981, 4130.359794231891, 4188.916221675565, 4194.07072746537, 3886.348301971665, 4129.15352352767, 4176.937345939813, 4182.832686692529, 4191.308434108384, 3458.515807786912, 3627.0957747182674, 4029.37544680575, 4059.330210515276, 4079.6537142742973, 3438.0373495421322, 3525.612182659931, 3793.715471339661, 3848.2880667076543, 4103.815328310204, 3056.0, 3241.416545021895, 3560.643935606419, 3576.856925722968, 3587.8744243951596, 4362.738235705868, 4469.1061787642275, 4618.630509559227, 5005.523370605643, 5194.454582268883, 4684.882877928045, 4724.850193162103, 4746.264329028972, 4765.880852978668, 4773.761311934424, 4657.301404789275, 4756.571908528249, 4770.4019153583595, 4869.711235719206, 5021.995600879802, 3144.3554071415306, 3781.867634632802, 3861.227754449121, 3879.301725689375, 3920.4315374270573, 6036.89100113442, 6042.607230530046, 6050.757969681207, 6052.4552790248645, 6108.847278818021, 3936.645701886844, 3957.9802100989887, 3959.940600890989, 3960.485136932238, 3965.1673148638533, 3868.3423817951016, 3992.744099583726, 4009.639132478091, 4035.0, 4035.8183881725395, 3904.668103211202, 4032.5684382044537, 4567.543245675406, 4644.281615802175, 4732.479427262998, 4554.544545545419, 4835.646019114653, 5871.266091738533, 5888.587798854125, 6000.819975400657, 1056.4242487844144, 1160.6982184631888, 1310.7706151423463, 1349.6625843539116, 1350.4065203060484, 751.706834334599, 780.8282177920848, 832.8673004713585, 894.2264940826138, 929.7675581104724, 4533.52397998212, 4736.715797052188, 4776.065693394181, 5431.728413579384, 5447.5641948644015, 4970.975145124324, 5620.91568626471, 5834.445727655866, 5880.382559831156, 5880.8235752927485, 5105.463926287761, 5182.144946083854, 5314.74123497512, 5332.946670533245, 5470.234167216593, 2376.8692721900115, 2394.844335118212, 2448.013423623177, 2624.3439140214946, 2648.9801426450954, 5033.0, 5037.823676171316, 5195.428502864682, 5223.582113430993, 5247.921281180786, 4482.551744825491, 4905.533974272438, 5061.772220250024, 5149.2791009258735, 6161.568690191733, 4887.780049897692, 5873.353931067579, 6163.781038826019, 6244.968775156095, 6321.1958911226875, 5289.127293996447, 5982.162497250301, 5995.481897603105, 6016.954235559756, 6037.366076562073, 4867.732274724783, 5080.872978175576, 5586.899270904687, 5966.731497082728, 5979.774146300085, 6199.380061993765, 6216.35266654346, 6248.468880145057, 6265.467435268053, 6361.363863613787, 3651.8721844735305, 3817.8472861085247, 3922.8038598070552, 3982.980085099556, 3997.9205614483344, 4892.339534162842, 4995.775190116527, 5130.846074617767, 5603.91974707447, 5938.970305148447, 4765.0, 4827.927581086216, 5368.57051435892, 5682.034054210877, 5778.191053252464, 4347.826086956512, 5015.648904576645, 5659.490645841894, 5873.327635925558, 5879.735411906389, 5408.23603928012, 5595.440455954535, 5647.616333998197, 5752.942470575408, 5795.449432303924, 5285.4714528548375, 5580.832575022756, 5717.371089180104, 5778.971105144447, 5854.151148083589, 5675.517581005661, 5686.635207550241, 5693.743781529758, 5749.942500574941, 5787.0], "value": 4064.0402455329654, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [612.534473799912, 724.4856152131986, 786.1823703348518, 790.9920900790991, 805.3355981315416, 14492.63768405789, 15278.847211527882, 15429.382824687, 15453.768193477106, 15503.457378991723, 10564.049235568797, 13300.401481933312, 14077.084989475678, 14125.364358603862, 14209.289535523209, 710.7761055267574, 732.059303794624, 879.6217626420637, 905.2305540290766, 916.3585490156884, 885.2961895293257, 895.9462432254086, 920.2453987730064, 921.0789210789211, 922.3267015078997, 806.2904643913348, 978.5400861595039, 978.9755256118583, 979.6179489998871, 980.7842274699555, 783.1933108897845, 953.5708930981051, 973.255459573427, 973.547300505265, 978.432509144698, 726.2555880222814, 729.5039373226205, 731.8060713910818, 732.8754111801006, 735.4704612678863, 14992.925035374754, 15064.0, 15198.544043678707, 15326.693466130726, 15457.613559660986, 14368.20974846383, 14742.778858317133, 14775.852241477554, 14787.260636968216, 14892.0, 11750.529978800838, 11801.055915526736, 11845.17083804134, 11885.584004559885, 11986.0, 3794.430835374716, 3811.8856434307013, 3813.809309534512, 3821.48409964654, 3967.1867267210155, 70634.0, 94760.0, 98747.0, 99227.50386248095, 99353.0129397408, 8961.372703910663, 11071.0, 11129.332240065622, 11227.550897964153, 11248.493817778219, 22878.885605571864, 22885.199018034287, 23126.65310020351, 23157.0, 23317.18389856381, 7676.462647614725, 7696.884546731803, 7801.609919504002, 7951.244631759973, 8015.519068855887, 4353.934690979638, 4370.169667763178, 4374.4531933508315, 4473.709208901476, 4550.795214215302, 3361.680640339213, 3445.8621655133516, 3448.4827275908815, 3657.5610926688437, 3814.1799513104816, 618.5762752514541, 687.3161204601454, 695.4818660098158, 709.0711168369453, 718.3498933465222, 574.9732194016043, 575.6315957787019, 629.156929714176, 662.3078882567728, 716.3588588213623, 8761.561921904007, 9169.358144929924, 9272.30457715674, 9288.767780805534, 9325.860112098324, 646.4989633034342, 659.3472462262422, 673.5117040145855, 680.1979118122424, 699.618707804248, 4255.9347144004205, 4413.470383553929, 4619.491855895849, 4672.369230153954, 4725.952740472551, 83280.58359708163, 89846.0, 96617.0, 108145.0, 113073.43463282795, 95758.0424195763, 98779.65254343206, 99640.50179749055, 101758.49120754348, 101943.0, 71901.28098718946, 73915.15212119633, 76795.23204767992, 79179.0, 85121.14878851132, 9628.229741620793, 9709.6601618943, 9857.457839818879, 9872.555734991798, 9957.751056223362, 49336.01327973492, 50644.24033639572, 53051.55164914268, 55332.0, 58032.4196758027, 63970.0, 74444.25555744373, 74608.25391746231, 77849.61075194587, 79242.0, 12310.155853714417, 12436.067294953096, 12880.484780608671, 12927.418266177856, 12944.741105177656, 14092.78860817068, 14261.42954281817, 14369.353379098175, 14678.266086695408, 14711.485098021518, 12934.353282336035, 12995.610131696065, 13299.66750831237, 13444.39500222512, 13736.244506552235, 13106.344682766014, 13433.798493022809, 13490.460381584626, 13493.0, 13662.453501860213, 12947.223166610034, 12947.223166610034, 13240.602781916557, 13243.404046817725, 13422.530211442552, 8573.65705371803, 10988.450577470934, 11129.387883666475, 11339.829902551302, 11476.483558239732, 8224.465409748347, 10055.346402483816, 10996.175286853679, 11213.327200368005, 11263.549458021915], "value": 7523.541969588222, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1651.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 125.0, 143.0, 109.0, 103.0, 97.0, 98.0, 111.0, 152.0, 174.0], "value": 122.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 120.0, 158.0, 139.0, 108.0, 137.0, 116.0, 114.0, 143.0, 114.0], "value": 132.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 213.0, 218.0, 205.0, 255.0, 198.0, 267.0, 279.0, 193.0, 203.0], "value": 223.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 58.0, 80.0, 75.0, 52.0, 58.0, 69.0, 106.0, 81.0], "value": 72.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 93.0, 87.0, 72.0, 70.0, 71.0, 89.0, 69.0, 72.0, 85.0], "value": 79.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 84.0, 85.0, 90.0, 86.0, 90.0, 87.0, 88.0, 88.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 139.0, 109.0, 176.0, 175.0, 176.0, 139.0, 174.0, 175.0, 99.0], "value": 153.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 141.0, 86.0, 87.0, 88.0, 126.0, 116.0, 89.0, 88.0, 90.0], "value": 105.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 59.0, 93.0, 88.0, 57.0, 58.0, 59.0, 59.0, 58.0, 57.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 88.0, 101.0, 70.0, 89.0, 61.0, 72.0, 109.0, 79.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 88.0, 87.0, 89.0, 88.0, 88.0, 103.0, 113.0, 114.0], "value": 100.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 162.0, 161.0, 169.0, 157.0, 167.0, 165.0, 164.0, 219.0, 151.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 180.0, 177.0, 246.0, 201.0, 175.0, 174.0, 173.0, 173.0, 176.0], "value": 185.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 77.0, 91.0, 93.0, 88.0, 71.0, 73.0, 68.0, 83.0], "value": 78.4, "unit": "score"}]}]} 22:37:36 INFO - Return code: 0 22:37:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:37:36 ERROR - # TBPL SUCCESS # 22:37:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:37:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:37:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:37:36 INFO - 2016-10-27 22:37:36.616 screenresolution[1950:16530] starting screenresolution argv=screenresolution get 22:37:36 INFO - 2016-10-27 22:37:36.619 screenresolution[1950:16530] Display 0: 1600x1200x32@60 22:37:36 INFO - 2016-10-27 22:37:36.624 screenresolution[1951:16533] starting screenresolution argv=screenresolution list 22:37:36 INFO - Available Modes on Display 0 22:37:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:37:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:37:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:37:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:37:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:37:36 INFO - Intel Iris: 22:37:36 INFO - Chipset Model: Intel Iris 22:37:36 INFO - Type: GPU 22:37:36 INFO - Bus: Built-In 22:37:36 INFO - VRAM (Dynamic, Max): 1536 MB 22:37:36 INFO - Vendor: Intel (0x8086) 22:37:36 INFO - Device ID: 0x0a2e 22:37:36 INFO - Revision ID: 0x0009 22:37:36 INFO - Displays: 22:37:36 INFO - Display: 22:37:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:37:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:37:36 INFO - Display Serial Number: 1600x1200 60 22:37:36 INFO - Main Display: Yes 22:37:36 INFO - Mirror: Off 22:37:36 INFO - Online: Yes 22:37:36 INFO - Rotation: Supported 22:37:36 INFO - Return code: 0 22:37:36 INFO - Running post-action listener: _resource_record_post_action 22:37:36 INFO - [mozharness: 2016-10-28 05:37:36.859194Z] Finished run-tests step (success) 22:37:36 INFO - Running post-run listener: _resource_record_post_run 22:37:36 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1533440; Write bytes: 707962368; Read time: 13; Write time: 2051 22:37:36 INFO - TinderboxPrint: CPU usage
54.4% 22:37:36 INFO - TinderboxPrint: I/O read bytes / time
1,533,440 / 13 22:37:36 INFO - TinderboxPrint: I/O write bytes / time
707,962,368 / 2,051 22:37:36 INFO - TinderboxPrint: CPU idle
2,209.8 (45.6%) 22:37:36 INFO - TinderboxPrint: CPU system
981.4 (20.2%) 22:37:36 INFO - TinderboxPrint: CPU user
1,658.6 (34.2%) 22:37:36 INFO - TinderboxPrint: Swap in / out
406,319,104 / 0 22:37:36 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 397832704; Write bytes: 392414720; Read time: 29644; Write time: 844 22:37:36 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1533440; Write bytes: 299175936; Read time: 13; Write time: 1154 22:37:37 INFO - Running post-run listener: _upload_blobber_files 22:37:37 INFO - Blob upload gear active. 22:37:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:37:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:37:37 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'] 22:37:37 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 22:37:37 INFO - (blobuploader) - INFO - Open directory for files ... 22:37:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:37:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:37:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:38 INFO - (blobuploader) - INFO - Done attempting. 22:37:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:37:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:37:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:39 INFO - (blobuploader) - INFO - Done attempting. 22:37:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:37:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:37:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:42 INFO - (blobuploader) - INFO - Done attempting. 22:37:42 INFO - (blobuploader) - INFO - Iteration through files over. 22:37:42 INFO - Return code: 0 22:37:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:37:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:37:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06abb4376b874a90cc8b10ca08525bff85c69cec6a99a0b65ba4a38de5967a2a0c80a02e1fcabc775634f7f22c293581ac3ac5ba171cd4e7ddada5ce18cc07c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0df6faa76f010c4529ba1733d0a16156d22a7ccbd80c7590369d6a520b1665c851e5c344463a3ddc4773b976d70ae90a2f0921e988c168bd0dcf5d80b71724f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d567352533ecfdd4cfbef23693a0408b6d75fa55fbafd57ac701d10db92003e6569258f5eedeab9f4d6f36725ce08f0b69dd5ecb0b1fb1b02cd4488b52d2375"} 22:37:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:37:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:37:42 INFO - Contents: 22:37:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06abb4376b874a90cc8b10ca08525bff85c69cec6a99a0b65ba4a38de5967a2a0c80a02e1fcabc775634f7f22c293581ac3ac5ba171cd4e7ddada5ce18cc07c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0df6faa76f010c4529ba1733d0a16156d22a7ccbd80c7590369d6a520b1665c851e5c344463a3ddc4773b976d70ae90a2f0921e988c168bd0dcf5d80b71724f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d567352533ecfdd4cfbef23693a0408b6d75fa55fbafd57ac701d10db92003e6569258f5eedeab9f4d6f36725ce08f0b69dd5ecb0b1fb1b02cd4488b52d2375"} 22:37:42 INFO - Running post-run listener: copy_logs_to_upload_dir 22:37:42 INFO - Copying logs to upload dir... 22:37:42 INFO - mkdir: /builds/slave/test/build/upload/logs 22:37:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.178690 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-27 22:37:42.643930) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-27 22:37:42.648572) ========= 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.w5EbPZNsH3/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.o3Jtyjr5nh/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06abb4376b874a90cc8b10ca08525bff85c69cec6a99a0b65ba4a38de5967a2a0c80a02e1fcabc775634f7f22c293581ac3ac5ba171cd4e7ddada5ce18cc07c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0df6faa76f010c4529ba1733d0a16156d22a7ccbd80c7590369d6a520b1665c851e5c344463a3ddc4773b976d70ae90a2f0921e988c168bd0dcf5d80b71724f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d567352533ecfdd4cfbef23693a0408b6d75fa55fbafd57ac701d10db92003e6569258f5eedeab9f4d6f36725ce08f0b69dd5ecb0b1fb1b02cd4488b52d2375"} build_url:https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011552 build_url: 'https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/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/06abb4376b874a90cc8b10ca08525bff85c69cec6a99a0b65ba4a38de5967a2a0c80a02e1fcabc775634f7f22c293581ac3ac5ba171cd4e7ddada5ce18cc07c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0df6faa76f010c4529ba1733d0a16156d22a7ccbd80c7590369d6a520b1665c851e5c344463a3ddc4773b976d70ae90a2f0921e988c168bd0dcf5d80b71724f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d567352533ecfdd4cfbef23693a0408b6d75fa55fbafd57ac701d10db92003e6569258f5eedeab9f4d6f36725ce08f0b69dd5ecb0b1fb1b02cd4488b52d2375"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-27 22:37:42.687739) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 22:37:42.688056) ========= 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.w5EbPZNsH3/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.o3Jtyjr5nh/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005336 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 22:37:42.739383) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-27 22:37:42.743726) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-27 22:37:42.744070) ========= ========= Total master_lag: 0.21 =========