builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0160 starttime: 1475673406.4 results: success (0) buildid: 20161005030211 builduid: 22f29386e03d4cf09dbcfebf284b6b90 revision: ea104eeb14cc54da9a06c3766da63f73117723a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:46.403324) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:46.403766) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:46.404098) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:46.428959) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:46.429295) ========= 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.KWpY7mXVf6/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.MYJ1fdb0wm/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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-05 06:16:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 154M=0s 2016-10-05 06:16:46 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106868 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:46.557837) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:46.558156) ========= 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.KWpY7mXVf6/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.MYJ1fdb0wm/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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.020908 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:46.607193) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-05 06:16:46.607561) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ea104eeb14cc54da9a06c3766da63f73117723a0 --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 ea104eeb14cc54da9a06c3766da63f73117723a0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KWpY7mXVf6/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.MYJ1fdb0wm/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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-05 06:16:46,679 truncating revision to first 12 chars 2016-10-05 06:16:46,679 Setting DEBUG logging. 2016-10-05 06:16:46,679 attempt 1/10 2016-10-05 06:16:46,679 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ea104eeb14cc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-05 06:16:47,370 unpacking tar archive at: mozilla-central-ea104eeb14cc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.953526 ========= master_lag: 2.19 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-05 06:16:49.748694) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:49.749056) ========= script_repo_revision: ea104eeb14cc54da9a06c3766da63f73117723a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:49.749514) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:49.749819) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 06:16:49.769435) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-05 06:16:49.769867) ========= /tools/buildbot/bin/python 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', '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.KWpY7mXVf6/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.MYJ1fdb0wm/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 06:16:49 INFO - MultiFileLogger online at 20161005 06:16:49 in /builds/slave/test 06:16:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:16:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:16:49 INFO - {'append_to_log': False, 06:16:49 INFO - 'base_work_dir': '/builds/slave/test', 06:16:49 INFO - 'blob_upload_branch': 'Firefox', 06:16:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:16:49 INFO - 'branch': 'Firefox', 06:16:49 INFO - 'buildbot_json_path': 'buildprops.json', 06:16:49 INFO - 'config_files': ('talos/mac_config.py',), 06:16:49 INFO - 'default_actions': ('clobber', 06:16:49 INFO - 'read-buildbot-config', 06:16:49 INFO - 'download-and-extract', 06:16:49 INFO - 'populate-webroot', 06:16:49 INFO - 'create-virtualenv', 06:16:49 INFO - 'install', 06:16:49 INFO - 'run-tests'), 06:16:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:16:49 INFO - 'download_minidump_stackwalk': True, 06:16:49 INFO - 'download_symbols': 'ondemand', 06:16:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:16:49 INFO - 'tooltool.py': '/tools/tooltool.py', 06:16:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:16:49 INFO - '/tools/misc-python/virtualenv.py')}, 06:16:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:16:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:16:49 INFO - 'installer_path': 'installer.exe', 06:16:49 INFO - 'log_level': 'info', 06:16:49 INFO - 'log_name': 'talos', 06:16:49 INFO - 'log_to_console': True, 06:16:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:16:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:16:49 INFO - 'opt_config_files': (), 06:16:49 INFO - 'pip_index': False, 06:16:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:16:49 INFO - 'cmd': ('bash', 06:16:49 INFO - '-c', 06:16:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:16:49 INFO - 'enabled': True, 06:16:49 INFO - 'halt_on_failure': False, 06:16:49 INFO - 'name': 'check_screen_resolution'},), 06:16:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:16:49 INFO - 'cmd': ('bash', 06:16:49 INFO - '-c', 06:16:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:16:49 INFO - 'enabled': True, 06:16:49 INFO - 'halt_on_failure': False, 06:16:49 INFO - 'name': 'check_screen_resolution'},), 06:16:49 INFO - 'run_cmd_checks_enabled': True, 06:16:49 INFO - 'sps_profile': False, 06:16:49 INFO - 'sps_profile_interval': 0, 06:16:49 INFO - 'suite': 'dromaeojs-e10s', 06:16:49 INFO - 'system_bits': '32', 06:16:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:16:49 INFO - 'title': 't-yosemite-r7-0160', 06:16:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:16:49 INFO - 'use_talos_json': True, 06:16:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:16:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:16:49 INFO - 'work_dir': 'build'} 06:16:49 INFO - [mozharness: 2016-10-05 13:16:49.969179Z] Running clobber step. 06:16:49 INFO - Running pre-action listener: _resource_record_pre_action 06:16:49 INFO - Running main action method: clobber 06:16:49 INFO - rmtree: /builds/slave/test/build 06:16:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:16:51 INFO - Running post-action listener: _resource_record_post_action 06:16:51 INFO - [mozharness: 2016-10-05 13:16:51.564333Z] Finished clobber step (success) 06:16:51 INFO - [mozharness: 2016-10-05 13:16:51.564572Z] Running read-buildbot-config step. 06:16:51 INFO - Running pre-action listener: _resource_record_pre_action 06:16:51 INFO - Running main action method: read_buildbot_config 06:16:51 INFO - Using buildbot properties: 06:16:51 INFO - { 06:16:51 INFO - "project": "", 06:16:51 INFO - "product": "firefox", 06:16:51 INFO - "script_repo_revision": "production", 06:16:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:16:51 INFO - "repository": "", 06:16:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:16:51 INFO - "buildid": "20161005030211", 06:16:51 INFO - "stage_platform": "macosx64", 06:16:51 INFO - "basedir": "/builds/slave/test", 06:16:51 INFO - "nightly_build": "True", 06:16:51 INFO - "buildnumber": 3, 06:16:51 INFO - "slavename": "t-yosemite-r7-0160", 06:16:51 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 06:16:51 INFO - "platform": "yosemite_r7", 06:16:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:16:51 INFO - "branch": "mozilla-central", 06:16:51 INFO - "repo_path": "mozilla-central", 06:16:51 INFO - "revision": "ea104eeb14cc54da9a06c3766da63f73117723a0", 06:16:51 INFO - "pgo_build": "False", 06:16:51 INFO - "builduid": "22f29386e03d4cf09dbcfebf284b6b90", 06:16:51 INFO - "slavebuilddir": "test" 06:16:51 INFO - } 06:16:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 06:16:51 INFO - Running post-action listener: _resource_record_post_action 06:16:51 INFO - [mozharness: 2016-10-05 13:16:51.570438Z] Finished read-buildbot-config step (success) 06:16:51 INFO - [mozharness: 2016-10-05 13:16:51.570647Z] Running download-and-extract step. 06:16:51 INFO - Running pre-action listener: _resource_record_pre_action 06:16:51 INFO - Running main action method: download_and_extract 06:16:51 INFO - mkdir: /builds/slave/test/build/tests 06:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:51 INFO - trying https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 06:16:51 INFO - Downloading https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/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 06:16:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/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 06:16:52 INFO - Downloaded 1199 bytes. 06:16:52 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 06:16:52 INFO - Using the following test package requirements: 06:16:52 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:16:52 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:16:52 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 06:16:52 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:16:52 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 06:16:52 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:16:52 INFO - u'jsshell-mac.zip'], 06:16:52 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:16:52 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 06:16:52 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:16:52 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:16:52 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 06:16:52 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:16:52 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 06:16:52 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:16:52 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 06:16:52 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:16:52 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 06:16:52 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:16:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:16:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:16:52 INFO - Fetch https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:16:53 INFO - Http code: 200 06:16:53 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 06:16:53 INFO - x-amz-cf-id: 2HiTEx04-dplhFEusmy-qds5kBNPcVzJd5WVr6Vte7p8lyFucTUgWg== 06:16:53 INFO - x-amz-version-id: SCAC1qKe_wo6MAx9QOfojq6UvdXoMJl9 06:16:53 INFO - Expected file size: 16477043 06:16:53 INFO - Obtained file size: 16477043 06:17:00 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 06:17:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:17:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:17:00 INFO - Fetch https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:17:00 INFO - Http code: 200 06:17:00 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 06:17:00 INFO - x-amz-cf-id: L9_PmC_D24qKLPM13ukuvQaewH0TjqOqRvs4crqRWk82DQo0pIZQjw== 06:17:00 INFO - x-amz-version-id: SCAC1qKe_wo6MAx9QOfojq6UvdXoMJl9 06:17:00 INFO - Expected file size: 16477043 06:17:00 INFO - Obtained file size: 16477043 06:17:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 06:17:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:17:07 INFO - Fetch https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 06:17:08 INFO - Http code: 200 06:17:08 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 06:17:08 INFO - x-amz-cf-id: LAZjJHQwHKmu4dsSilmzfoZjs0MV4hAV9z4RsdKIMHVBCgmXf5Wcfw== 06:17:08 INFO - x-amz-version-id: ov.T110faDKlOcMF5cQqlSxjFZCvdcEZ 06:17:08 INFO - Expected file size: 14409006 06:17:08 INFO - Obtained file size: 14409006 06: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')]} 06:17:08 INFO - trying https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:17:08 INFO - Downloading https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 06:17:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/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 06:17:11 INFO - Downloaded 111862693 bytes. 06:17:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:17:11 INFO - mkdir: /builds/slave/test/properties 06:17:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:17:11 INFO - Writing to file /builds/slave/test/properties/build_url 06:17:11 INFO - Contents: 06:17:11 INFO - build_url:https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:17:12 INFO - Running post-action listener: _resource_record_post_action 06:17:12 INFO - Running post-action listener: set_extra_try_arguments 06:17:12 INFO - [mozharness: 2016-10-05 13:17:12.122933Z] Finished download-and-extract step (success) 06:17:12 INFO - [mozharness: 2016-10-05 13:17:12.123087Z] Running populate-webroot step. 06:17:12 INFO - Running pre-action listener: _resource_record_pre_action 06:17:12 INFO - Running main action method: populate_webroot 06:17:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:17:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:17:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:17:12 INFO - '--tppagecycles', 06:17:12 INFO - '7'], 06:17:12 INFO - 'tests': ['tsvgm']}}, 06:17:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:17:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:17:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:17:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:17:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:17:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:17:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:17:12 INFO - 'talos_options': ['--disable-e10s'], 06:17:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:17:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:17:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:17:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:17:12 INFO - 'talos_options': ['--disable-e10s'], 06:17:12 INFO - 'tests': ['damp', 'tps']}, 06:17:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:17:12 INFO - 'tests': ['damp', 'tps']}, 06:17:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:17:12 INFO - 'tests': ['dromaeo_dom']}, 06:17:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:17:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:17:12 INFO - 'tests': ['basic_compositor_video']}, 06:17:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:17:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:17:12 INFO - 'tests': ['a11yr', 06:17:12 INFO - 'ts_paint', 06:17:12 INFO - 'tpaint', 06:17:12 INFO - 'sessionrestore', 06:17:12 INFO - 'sessionrestore_no_auto_restore', 06:17:12 INFO - 'tabpaint']}, 06:17:12 INFO - 'other-e10s': {'tests': ['a11yr', 06:17:12 INFO - 'ts_paint', 06:17:12 INFO - 'tpaint', 06:17:12 INFO - 'sessionrestore', 06:17:12 INFO - 'sessionrestore_no_auto_restore', 06:17:12 INFO - 'tabpaint']}, 06:17:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:17:12 INFO - 'ts_paint', 06:17:12 INFO - 'tpaint', 06:17:12 INFO - 'sessionrestore', 06:17:12 INFO - 'sessionrestore_no_auto_restore', 06:17:12 INFO - 'tabpaint']}, 06:17:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:17:12 INFO - 'ts_paint', 06:17:12 INFO - 'tpaint', 06:17:12 INFO - 'sessionrestore', 06:17:12 INFO - 'sessionrestore_no_auto_restore', 06:17:12 INFO - 'tabpaint']}, 06:17:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:17:12 INFO - 'tests': ['a11yr', 06:17:12 INFO - 'ts_paint', 06:17:12 INFO - 'tpaint', 06:17:12 INFO - 'sessionrestore', 06:17:12 INFO - 'sessionrestore_no_auto_restore', 06:17:12 INFO - 'tabpaint']}, 06:17:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:17:12 INFO - 'tests': ['a11yr', 06:17:12 INFO - 'ts_paint', 06:17:12 INFO - 'tpaint', 06:17:12 INFO - 'sessionrestore', 06:17:12 INFO - 'sessionrestore_no_auto_restore', 06:17:12 INFO - 'tabpaint']}, 06:17:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:17:12 INFO - 'tests': ['tsvgx', 06:17:12 INFO - 'tsvgr_opacity', 06:17:12 INFO - 'tart', 06:17:12 INFO - 'tscrollx', 06:17:12 INFO - 'cart']}, 06:17:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:17:12 INFO - 'tsvgr_opacity', 06:17:12 INFO - 'tart', 06:17:12 INFO - 'tscrollx', 06:17:12 INFO - 'cart']}, 06:17:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:17:12 INFO - 'talos_options': ['--disable-e10s'], 06:17:12 INFO - 'tests': ['tp5o']}, 06:17:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:17:12 INFO - 'tests': ['tp5o']}, 06:17:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:17:12 INFO - 'talos_options': ['--disable-e10s', 06:17:12 INFO - '--xperf_path', 06:17:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:17:12 INFO - 'tests': ['tp5n']}, 06:17:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:17:12 INFO - 'talos_options': ['--xperf_path', 06:17:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:17:12 INFO - 'tests': ['tp5n']}}, 06:17:12 INFO - 'talos.zip': {'path': '', 06:17:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:17:12 INFO - Running post-action listener: _resource_record_post_action 06:17:12 INFO - [mozharness: 2016-10-05 13:17:12.141802Z] Finished populate-webroot step (success) 06:17:12 INFO - [mozharness: 2016-10-05 13:17:12.141932Z] Running create-virtualenv step. 06:17:12 INFO - Running pre-action listener: _resource_record_pre_action 06:17:12 INFO - Running main action method: create_virtualenv 06:17:12 INFO - Creating virtualenv /builds/slave/test/build/venv 06:17:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:17:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:17:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:17:12 INFO - Using real prefix '/tools/python27' 06:17:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:17:12 INFO - Installing distribute.............................................................................................................................................................................................done. 06:17:15 INFO - Installing pip.................done. 06:17:15 INFO - Return code: 0 06:17:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:17:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:17:16 INFO - Reading from file tmpfile_stdout 06:17:16 INFO - Output received: 06:17:16 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 06:17:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:16 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 0x10f16c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe3a581240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef80d30>, '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 0x10eda6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef78b20>, '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.KWpY7mXVf6/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.MYJ1fdb0wm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:17:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:17:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWpY7mXVf6/Render', 06:17:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:16 INFO - 'HOME': '/Users/cltbld', 06:17:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:16 INFO - 'LOGNAME': 'cltbld', 06:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:16 INFO - 'MOZ_NO_REMOTE': '1', 06:17:16 INFO - 'NO_EM_RESTART': '1', 06:17:16 INFO - 'PAGER': '/bin/cat', 06:17:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:16 INFO - 'PWD': '/builds/slave/test', 06:17:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:16 INFO - 'SHELL': '/bin/bash', 06:17:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MYJ1fdb0wm/Listeners', 06:17:16 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 06:17:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:16 INFO - 'USER': 'cltbld', 06:17:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:16 INFO - 'XPC_FLAGS': '0x0', 06:17:16 INFO - 'XPC_SERVICE_NAME': '0', 06:17:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 06:17:16 INFO - Cleaning up... 06:17:16 INFO - Return code: 0 06:17:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:16 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 0x10f16c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe3a581240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef80d30>, '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 0x10eda6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef78b20>, '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.KWpY7mXVf6/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.MYJ1fdb0wm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:17:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:17:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWpY7mXVf6/Render', 06:17:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:16 INFO - 'HOME': '/Users/cltbld', 06:17:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:16 INFO - 'LOGNAME': 'cltbld', 06:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:16 INFO - 'MOZ_NO_REMOTE': '1', 06:17:16 INFO - 'NO_EM_RESTART': '1', 06:17:16 INFO - 'PAGER': '/bin/cat', 06:17:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:16 INFO - 'PWD': '/builds/slave/test', 06:17:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:16 INFO - 'SHELL': '/bin/bash', 06:17:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MYJ1fdb0wm/Listeners', 06:17:16 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 06:17:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:16 INFO - 'USER': 'cltbld', 06:17:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:16 INFO - 'XPC_FLAGS': '0x0', 06:17:16 INFO - 'XPC_SERVICE_NAME': '0', 06:17:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:16 INFO - Downloading/unpacking psutil>=3.1.1 06:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:17:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:17:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:17:20 INFO - Installing collected packages: psutil 06:17:20 INFO - Running setup.py install for psutil 06:17:20 INFO - building 'psutil._psutil_osx' extension 06:17:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 06:17:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 06:17:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 06:17:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 06:17:20 INFO - building 'psutil._psutil_posix' extension 06:17:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 06:17:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:17:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:17:20 INFO - ^ 06:17:20 INFO - 1 warning generated. 06:17:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 06:17:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:17:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:17:20 INFO - Successfully installed psutil 06:17:20 INFO - Cleaning up... 06:17:21 INFO - Return code: 0 06:17:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:21 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 0x10f16c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe3a581240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef80d30>, '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 0x10eda6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef78b20>, '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.KWpY7mXVf6/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.MYJ1fdb0wm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:17:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:17:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWpY7mXVf6/Render', 06:17:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:21 INFO - 'HOME': '/Users/cltbld', 06:17:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:21 INFO - 'LOGNAME': 'cltbld', 06:17:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:21 INFO - 'MOZ_NO_REMOTE': '1', 06:17:21 INFO - 'NO_EM_RESTART': '1', 06:17:21 INFO - 'PAGER': '/bin/cat', 06:17:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:21 INFO - 'PWD': '/builds/slave/test', 06:17:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:21 INFO - 'SHELL': '/bin/bash', 06:17:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MYJ1fdb0wm/Listeners', 06:17:21 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 06:17:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:21 INFO - 'USER': 'cltbld', 06:17:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:21 INFO - 'XPC_FLAGS': '0x0', 06:17:21 INFO - 'XPC_SERVICE_NAME': '0', 06:17:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:17:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:17:23 INFO - Installing collected packages: mozsystemmonitor 06:17:23 INFO - Running setup.py install for mozsystemmonitor 06:17:24 INFO - Successfully installed mozsystemmonitor 06:17:24 INFO - Cleaning up... 06:17:24 INFO - Return code: 0 06:17:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:24 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 0x10f16c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe3a581240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef80d30>, '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 0x10eda6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef78b20>, '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.KWpY7mXVf6/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.MYJ1fdb0wm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:17:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:17:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWpY7mXVf6/Render', 06:17:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:24 INFO - 'HOME': '/Users/cltbld', 06:17:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:24 INFO - 'LOGNAME': 'cltbld', 06:17:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:24 INFO - 'MOZ_NO_REMOTE': '1', 06:17:24 INFO - 'NO_EM_RESTART': '1', 06:17:24 INFO - 'PAGER': '/bin/cat', 06:17:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:24 INFO - 'PWD': '/builds/slave/test', 06:17:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:24 INFO - 'SHELL': '/bin/bash', 06:17:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MYJ1fdb0wm/Listeners', 06:17:24 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 06:17:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:24 INFO - 'USER': 'cltbld', 06:17:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:24 INFO - 'XPC_FLAGS': '0x0', 06:17:24 INFO - 'XPC_SERVICE_NAME': '0', 06:17:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:24 INFO - Downloading/unpacking jsonschema==2.5.1 06:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:17:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:27 INFO - Downloading functools32-3.2.3-2.tar.gz 06:17:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:17:27 INFO - warning: no files found matching '*.txt' 06:17:27 INFO - no previously-included directories found matching 'build' 06:17:27 INFO - no previously-included directories found matching 'dist' 06:17:27 INFO - no previously-included directories found matching '.git*' 06:17:27 INFO - Installing collected packages: jsonschema, functools32 06:17:27 INFO - Running setup.py install for functools32 06:17:27 INFO - warning: no files found matching '*.txt' 06:17:27 INFO - no previously-included directories found matching 'build' 06:17:27 INFO - no previously-included directories found matching 'dist' 06:17:27 INFO - no previously-included directories found matching '.git*' 06:17:27 INFO - Successfully installed jsonschema functools32 06:17:27 INFO - Cleaning up... 06:17:27 INFO - Return code: 0 06:17:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:17:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:27 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 0x10f16c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe3a581240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef80d30>, '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 0x10eda6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef78b20>, '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.KWpY7mXVf6/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.MYJ1fdb0wm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:17:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:17:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:17:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWpY7mXVf6/Render', 06:17:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:27 INFO - 'HOME': '/Users/cltbld', 06:17:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:27 INFO - 'LOGNAME': 'cltbld', 06:17:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:27 INFO - 'MOZ_NO_REMOTE': '1', 06:17:27 INFO - 'NO_EM_RESTART': '1', 06:17:27 INFO - 'PAGER': '/bin/cat', 06:17:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:27 INFO - 'PWD': '/builds/slave/test', 06:17:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:27 INFO - 'SHELL': '/bin/bash', 06:17:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MYJ1fdb0wm/Listeners', 06:17:27 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 06:17:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:27 INFO - 'USER': 'cltbld', 06:17:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:27 INFO - 'XPC_FLAGS': '0x0', 06:17:27 INFO - 'XPC_SERVICE_NAME': '0', 06:17:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:17:27 INFO - Cleaning up... 06:17:27 INFO - Return code: 0 06:17:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:17:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:27 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 0x10f16c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe3a581240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef80d30>, '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 0x10eda6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef78b20>, '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.KWpY7mXVf6/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.MYJ1fdb0wm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:17:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:17:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:17:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWpY7mXVf6/Render', 06:17:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:27 INFO - 'HOME': '/Users/cltbld', 06:17:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:27 INFO - 'LOGNAME': 'cltbld', 06:17:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:27 INFO - 'MOZ_NO_REMOTE': '1', 06:17:27 INFO - 'NO_EM_RESTART': '1', 06:17:27 INFO - 'PAGER': '/bin/cat', 06:17:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:27 INFO - 'PWD': '/builds/slave/test', 06:17:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:27 INFO - 'SHELL': '/bin/bash', 06:17:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MYJ1fdb0wm/Listeners', 06:17:27 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 06:17:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:27 INFO - 'USER': 'cltbld', 06:17:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:27 INFO - 'XPC_FLAGS': '0x0', 06:17:27 INFO - 'XPC_SERVICE_NAME': '0', 06:17:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:28 INFO - Downloading/unpacking blobuploader==1.2.4 06:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:30 INFO - Downloading blobuploader-1.2.4.tar.gz 06:17:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:17:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:17:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:31 INFO - Downloading docopt-0.6.1.tar.gz 06:17:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:17:32 INFO - Installing collected packages: blobuploader, requests, docopt 06:17:32 INFO - Running setup.py install for blobuploader 06:17:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:17:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:17:32 INFO - Running setup.py install for requests 06:17:32 INFO - Running setup.py install for docopt 06:17:32 INFO - Successfully installed blobuploader requests docopt 06:17:32 INFO - Cleaning up... 06:17:32 INFO - Return code: 0 06:17:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:17:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:32 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 0x10f16c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe3a581240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef80d30>, '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 0x10eda6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef78b20>, '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.KWpY7mXVf6/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.MYJ1fdb0wm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:17:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:17:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:17:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWpY7mXVf6/Render', 06:17:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:32 INFO - 'HOME': '/Users/cltbld', 06:17:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:32 INFO - 'LOGNAME': 'cltbld', 06:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:32 INFO - 'MOZ_NO_REMOTE': '1', 06:17:32 INFO - 'NO_EM_RESTART': '1', 06:17:32 INFO - 'PAGER': '/bin/cat', 06:17:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:32 INFO - 'PWD': '/builds/slave/test', 06:17:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:32 INFO - 'SHELL': '/bin/bash', 06:17:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MYJ1fdb0wm/Listeners', 06:17:32 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 06:17:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:32 INFO - 'USER': 'cltbld', 06:17:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:32 INFO - 'XPC_FLAGS': '0x0', 06:17:32 INFO - 'XPC_SERVICE_NAME': '0', 06:17:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:17:33 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-DQzAC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:17:33 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-W7qw0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:17:33 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Xf9wca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:17:33 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-9_O_xy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:17:33 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-zlXtoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:17:33 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-1aJTBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:17:33 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-yHX5nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:17:33 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-AxS42N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:17:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-V9DiND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:17:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-6pGzSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:17:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-3Yt81C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:17:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-j0WmsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:17:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-YKAUwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:17:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Bw14XF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:17:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-D4jDY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:17:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-J76Anu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:17:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-OsIgnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:17:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:17:34 INFO - Running setup.py install for manifestparser 06:17:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:17:35 INFO - Running setup.py install for mozcrash 06:17:35 INFO - Running setup.py install for mozdebug 06:17:35 INFO - Running setup.py install for mozdevice 06:17:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:17:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:17:35 INFO - Running setup.py install for mozfile 06:17:35 INFO - Running setup.py install for mozhttpd 06:17:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:17:35 INFO - Running setup.py install for mozinfo 06:17:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:17:36 INFO - Running setup.py install for mozInstall 06:17:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:17:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:17:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:17:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:17:36 INFO - Running setup.py install for mozleak 06:17:36 INFO - Running setup.py install for mozlog 06:17:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:17:36 INFO - Running setup.py install for moznetwork 06:17:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:17:36 INFO - Running setup.py install for mozprocess 06:17:36 INFO - Running setup.py install for mozprofile 06:17:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:17:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:17:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:17:36 INFO - Running setup.py install for mozrunner 06:17:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:17:37 INFO - Running setup.py install for mozscreenshot 06:17:37 INFO - Running setup.py install for moztest 06:17:37 INFO - Running setup.py install for mozversion 06:17:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:17:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:17:37 INFO - Cleaning up... 06:17:37 INFO - Return code: 0 06:17:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:37 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 0x10f16c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe3a581240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef80d30>, '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 0x10eda6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef78b20>, '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.KWpY7mXVf6/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.MYJ1fdb0wm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:17:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:17:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:17:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWpY7mXVf6/Render', 06:17:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:37 INFO - 'HOME': '/Users/cltbld', 06:17:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:37 INFO - 'LOGNAME': 'cltbld', 06:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:37 INFO - 'MOZ_NO_REMOTE': '1', 06:17:37 INFO - 'NO_EM_RESTART': '1', 06:17:37 INFO - 'PAGER': '/bin/cat', 06:17:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:37 INFO - 'PWD': '/builds/slave/test', 06:17:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:37 INFO - 'SHELL': '/bin/bash', 06:17:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MYJ1fdb0wm/Listeners', 06:17:37 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 06:17:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:37 INFO - 'USER': 'cltbld', 06:17:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:37 INFO - 'XPC_FLAGS': '0x0', 06:17:37 INFO - 'XPC_SERVICE_NAME': '0', 06:17:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:17:37 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Zvuo76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:17:37 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:17:37 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-NsC2Dm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:17:38 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)) 06:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:17:38 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-FAXBC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:17:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:17:38 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Kz5z1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:17:38 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)) 06:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:17:38 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-NRbG8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:17:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 06:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:17:38 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-1TUrsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:17:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 06:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:17:38 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-82cGP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:17:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:17:38 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-WGSfib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:17:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:17:38 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-AX_jpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:17:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:17:38 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-ZSXLBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:17:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:17:38 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-M5MMuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:17:39 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-NnKOf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:17:39 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)) 06:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:17:39 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-nv0jfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:17:39 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-hJ986V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:17:39 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)) 06:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:17:39 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-vxUYLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:17:39 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-_J08O2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:17:39 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)) 06:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:17:39 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-mjuEkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:17:39 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)) 06:17:39 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)) 06:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:17:39 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)) 06:17:39 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)) 06:17:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:17:42 INFO - Downloading blessings-1.6.tar.gz 06:17:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:17:42 INFO - Installing collected packages: blessings 06:17:42 INFO - Running setup.py install for blessings 06:17:42 INFO - Successfully installed blessings 06:17:42 INFO - Cleaning up... 06:17:42 INFO - Return code: 0 06:17:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:17:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:17:42 INFO - Reading from file tmpfile_stdout 06:17:42 INFO - Current package versions: 06:17:42 INFO - blessings == 1.6 06:17:42 INFO - blobuploader == 1.2.4 06:17:42 INFO - docopt == 0.6.1 06:17:42 INFO - functools32 == 3.2.3-2 06:17:42 INFO - jsonschema == 2.5.1 06:17:42 INFO - manifestparser == 1.1 06:17:42 INFO - mozInstall == 1.12 06:17:42 INFO - mozcrash == 0.17 06:17:42 INFO - mozdebug == 0.1 06:17:42 INFO - mozdevice == 0.48 06:17:42 INFO - mozfile == 1.2 06:17:42 INFO - mozhttpd == 0.7 06:17:42 INFO - mozinfo == 0.9 06:17:42 INFO - mozleak == 0.1 06:17:42 INFO - mozlog == 3.4 06:17:42 INFO - moznetwork == 0.27 06:17:42 INFO - mozprocess == 0.23 06:17:42 INFO - mozprofile == 0.28 06:17:42 INFO - mozrunner == 6.12 06:17:42 INFO - mozscreenshot == 0.1 06:17:42 INFO - mozsystemmonitor == 0.3 06:17:42 INFO - moztest == 0.7 06:17:42 INFO - mozversion == 1.4 06:17:42 INFO - psutil == 3.1.1 06:17:42 INFO - requests == 1.2.3 06:17:42 INFO - wsgiref == 0.1.2 06:17:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:42 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 0x10f16c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe3a581240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef80d30>, '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 0x10eda6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef78b20>, '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.KWpY7mXVf6/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.MYJ1fdb0wm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:17:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:17:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWpY7mXVf6/Render', 06:17:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:42 INFO - 'HOME': '/Users/cltbld', 06:17:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:42 INFO - 'LOGNAME': 'cltbld', 06:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:42 INFO - 'MOZ_NO_REMOTE': '1', 06:17:42 INFO - 'NO_EM_RESTART': '1', 06:17:42 INFO - 'PAGER': '/bin/cat', 06:17:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:42 INFO - 'PWD': '/builds/slave/test', 06:17:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:42 INFO - 'SHELL': '/bin/bash', 06:17:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MYJ1fdb0wm/Listeners', 06:17:42 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 06:17:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:42 INFO - 'USER': 'cltbld', 06:17:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:42 INFO - 'XPC_FLAGS': '0x0', 06:17:42 INFO - 'XPC_SERVICE_NAME': '0', 06:17:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:17:43 INFO - Cleaning up... 06:17:43 INFO - Return code: 0 06:17:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:17:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:17:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:17:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:17:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f16c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe3a581240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef80d30>, '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 0x10eda6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef78b20>, '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.KWpY7mXVf6/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.MYJ1fdb0wm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:17:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:17:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:17:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWpY7mXVf6/Render', 06:17:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:43 INFO - 'HOME': '/Users/cltbld', 06:17:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:43 INFO - 'LOGNAME': 'cltbld', 06:17:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:43 INFO - 'MOZ_NO_REMOTE': '1', 06:17:43 INFO - 'NO_EM_RESTART': '1', 06:17:43 INFO - 'PAGER': '/bin/cat', 06:17:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:43 INFO - 'PWD': '/builds/slave/test', 06:17:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:43 INFO - 'SHELL': '/bin/bash', 06:17:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MYJ1fdb0wm/Listeners', 06:17:43 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 06:17:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:43 INFO - 'USER': 'cltbld', 06:17:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:43 INFO - 'XPC_FLAGS': '0x0', 06:17:43 INFO - 'XPC_SERVICE_NAME': '0', 06:17:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:17:44 INFO - Cleaning up... 06:17:44 INFO - Return code: 0 06:17:44 INFO - Running post-action listener: _resource_record_post_action 06:17:44 INFO - Running post-action listener: _start_resource_monitoring 06:17:44 INFO - Starting resource monitoring. 06:17:44 INFO - [mozharness: 2016-10-05 13:17:44.198881Z] Finished create-virtualenv step (success) 06:17:44 INFO - [mozharness: 2016-10-05 13:17:44.199892Z] Running install step. 06:17:44 INFO - Running pre-action listener: _resource_record_pre_action 06:17:44 INFO - Running main action method: install 06:17:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:17:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:17:44 INFO - Reading from file tmpfile_stdout 06:17:44 INFO - Detecting whether we're running mozinstall >=1.0... 06:17:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:17:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:17:44 INFO - Reading from file tmpfile_stdout 06:17:44 INFO - Output received: 06:17:44 INFO - Usage: mozinstall [options] installer 06:17:44 INFO - Options: 06:17:44 INFO - -h, --help show this help message and exit 06:17:44 INFO - -d DEST, --destination=DEST 06:17:44 INFO - Directory to install application into. [default: 06:17:44 INFO - "/builds/slave/test"] 06:17:44 INFO - --app=APP Application being installed. [default: firefox] 06:17:44 INFO - mkdir: /builds/slave/test/build/application 06:17:44 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'] 06:17:44 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 06:18:18 INFO - Reading from file tmpfile_stdout 06:18:18 INFO - Output received: 06:18:18 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 06:18:18 INFO - Running post-action listener: _resource_record_post_action 06:18:18 INFO - [mozharness: 2016-10-05 13:18:18.986830Z] Finished install step (success) 06:18:18 INFO - [mozharness: 2016-10-05 13:18:18.986973Z] Running run-tests step. 06:18:18 INFO - Running pre-action listener: _resource_record_pre_action 06:18:18 INFO - Running main action method: run_tests 06:18:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:18:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:18:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:18:19 INFO - 2016-10-05 06:18:19.001 screenresolution[1935:19828] starting screenresolution argv=screenresolution get 06:18:19 INFO - 2016-10-05 06:18:19.004 screenresolution[1935:19828] Display 0: 1600x1200x32@60 06:18:19 INFO - 2016-10-05 06:18:19.009 screenresolution[1936:19830] starting screenresolution argv=screenresolution list 06:18:19 INFO - Available Modes on Display 0 06:18:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:18:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:18:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:18:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:18:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:18:19 INFO - Intel Iris: 06:18:19 INFO - Chipset Model: Intel Iris 06:18:19 INFO - Type: GPU 06:18:19 INFO - Bus: Built-In 06:18:19 INFO - VRAM (Dynamic, Max): 1536 MB 06:18:19 INFO - Vendor: Intel (0x8086) 06:18:19 INFO - Device ID: 0x0a2e 06:18:19 INFO - Revision ID: 0x0009 06:18:19 INFO - Displays: 06:18:19 INFO - Display: 06:18:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:18:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:18:19 INFO - Display Serial Number: 1600x1200 60 06:18:19 INFO - Main Display: Yes 06:18:19 INFO - Mirror: Off 06:18:19 INFO - Online: Yes 06:18:19 INFO - Rotation: Supported 06:18:19 INFO - Return code: 0 06:18:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:18:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:18:19 INFO - Python 2.7.3 06:18:19 INFO - Return code: 0 06:18:19 INFO - grabbing minidump binary from tooltool 06:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:18:19 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 0x10ef80d30>, '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 0x10eda6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef78b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:18:19 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:18:19 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 06:18:19 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:18:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:18:19 INFO - Return code: 0 06:18:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:18:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:18:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:18:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:18:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:18:19 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0160', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 06:18:19 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0160 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 06:18:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWpY7mXVf6/Render', 06:18:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:19 INFO - 'HOME': '/Users/cltbld', 06:18:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:19 INFO - 'LOGNAME': 'cltbld', 06:18:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:18:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:18:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:19 INFO - 'MOZ_NO_REMOTE': '1', 06:18:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:18:19 INFO - 'NO_EM_RESTART': '1', 06:18:19 INFO - 'PAGER': '/bin/cat', 06:18:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:19 INFO - 'PWD': '/builds/slave/test', 06:18:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:18:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:19 INFO - 'SHELL': '/bin/bash', 06:18:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MYJ1fdb0wm/Listeners', 06:18:19 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 06:18:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:19 INFO - 'USER': 'cltbld', 06:18:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:19 INFO - 'XPC_FLAGS': '0x0', 06:18:19 INFO - 'XPC_SERVICE_NAME': '0', 06:18:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:19 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0160', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 06:18:19 INFO - mozversion application_buildid: 20161005030211 06:18:19 INFO - mozversion application_changeset: ea104eeb14cc54da9a06c3766da63f73117723a0 06:18:19 INFO - mozversion application_display_name: Nightly 06:18:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:18:19 INFO - mozversion application_name: Firefox 06:18:19 INFO - mozversion application_remotingname: firefox 06:18:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:18:19 INFO - mozversion application_vendor: Mozilla 06:18:19 INFO - mozversion application_version: 52.0a1 06:18:19 INFO - mozversion platform_buildid: 20161005030211 06:18:19 INFO - mozversion platform_changeset: ea104eeb14cc54da9a06c3766da63f73117723a0 06:18:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:18:19 INFO - mozversion platform_version: 52.0a1 06:18:19 INFO - using testdate: 1475673499 06:18:19 INFO - actual date: 1475673499 06:18:19 INFO - starting webserver on 'localhost:55808' 06:18:19 INFO - SUITE-START | Running 2 tests 06:18:19 INFO - TEST-START | dromaeo_css 06:18:19 INFO - operating with platform_type : mac_ 06:18:19 INFO - Initialising browser for dromaeo_css test... 06:18:19 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpeHQhLg/profile http://localhost:55808/getInfo.html) 06:18:22 INFO - PROCESS | 1946 | 2016-10-05 06:18:22.438 plugin-container[1949:19994] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d47, name = 'com.apple.tsm.portname' 06:18:22 INFO - PROCESS | 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:18:22 INFO - PROCESS | 1946 | 2016-10-05 06:18:22.439 plugin-container[1949:19994] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 06:18:22 INFO - PROCESS | 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:18:22 INFO - PROCESS | 1946 | 2016-10-05 06:18:22.439 plugin-container[1949:19994] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:18:22 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 06:18:22 INFO - PROCESS | 1946 | colorDepth:24 06:18:22 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 06:18:22 INFO - PROCESS | 1946 | __metrics 06:18:22 ERROR - PROCESS | 1946 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 06:18:23 INFO - TEST-INFO | 1946: exit 0 06:18:23 INFO - Browser initialized. 06:18:23 INFO - Running cycle 1/1 for dromaeo_css test... 06:18:23 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpeHQhLg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:18:25 INFO - PROCESS | 1950 | 2016-10-05 06:18:25.604 plugin-container[1951:20111] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431f, name = 'com.apple.tsm.portname' 06:18:25 INFO - PROCESS | 1950 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:18:25 INFO - PROCESS | 1950 | 2016-10-05 06:18:25.605 plugin-container[1951:20111] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e1f, name = 'com.apple.CFPasteboardClient' 06:18:25 INFO - PROCESS | 1950 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:18:25 INFO - PROCESS | 1950 | 2016-10-05 06:18:25.605 plugin-container[1951:20111] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:18:35 INFO - PROCESS | 1950 | RSS: Main: 240263168 06:18:35 INFO - PROCESS | 1950 | 06:19:24 INFO - PROCESS | 1950 | 1475673564979 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] 06:21:38 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55808/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55808/tests/dromaeo/cssquery-ext.html) 06:21:40 INFO - PROCESS | 1950 | RSS: Main: 253898752 06:21:40 INFO - PROCESS | 1950 | 06:24:42 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55808/tests/dromaeo/cssquery-ext.html (next: http://localhost:55808/tests/dromaeo/cssquery-jquery.html) 06:24:43 INFO - PROCESS | 1950 | RSS: Main: 266846208 06:24:43 INFO - PROCESS | 1950 | 06:27:45 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55808/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55808/tests/dromaeo/cssquery-mootools.html) 06:27:45 INFO - PROCESS | 1950 | RSS: Main: 270438400 06:27:45 INFO - PROCESS | 1950 | 06:30:47 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55808/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55808/tests/dromaeo/cssquery-prototype.html) 06:30:48 INFO - PROCESS | 1950 | RSS: Main: 270270464 06:30:48 INFO - PROCESS | 1950 | 06:33:51 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55808/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55808/tests/dromaeo/cssquery-yui.html) 06:33:51 INFO - PROCESS | 1950 | RSS: Main: 275783680 06:33:51 INFO - PROCESS | 1950 | 06:36:54 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55808/tests/dromaeo/cssquery-yui.html (next: http://localhost:55808/tests/dromaeo/cssquery-dojo.html) 06:36:54 INFO - PROCESS | 1950 | RSS: Main: 278257664 06:36:54 INFO - PROCESS | 1950 | 06:36:54 INFO - PROCESS | 1950 | __start_tp_report 06:36:54 INFO - PROCESS | 1950 | _x_x_mozilla_page_load 06:36:54 INFO - PROCESS | 1950 | _x_x_mozilla_page_load_details 06:36:54 INFO - PROCESS | 1950 | |i|pagename|runs| 06:36:54 INFO - PROCESS | 1950 | |0;dojo.html;3381.8309084545776;3394.2871996880667;3404.897853064407;3419.1281223288056;3424.332255210232;5656.943430565693;5710.457506536877;5761;5810.5932584719085;5843.79546715865;5696.914546281799;5739.713014349276;5768.509676677477;5798.478136967672;5812.0410132328225;5207.0887594670785;5221.295125158112;5283.181106928432;5325.4940780625975;5347.25138480613;5108.48915108488;5255.5795536357;5341.786328546854;5390.164524498709;5399.271098401705;5121.641485096045;5176.767045482962;5228.058949389108;5239.895202095937;5260.473952604709;4530.841420550265;5022.698638081727;5168.095583272912;5231.346081739783;5286.682799032071;4283.250431174532;5183.144781111112;5183.870403239949;5214.739263036833;5276.947230527722;5579.581531385163;5744.597878148531;5763.855903602402;5780.1907732917425;5828.184054232411;6143.235544005171;6197.690115494207;6220.315765265817;6249.500039996789;6259.906101408483;6232.034034724625;6260.624362538262;6268.87462250757;6268.937310626881;6294.653794041325;5205.9219111713355;5589;5806.68063256525;5834.183214349994;5967.6121052131475;6198.876022479525;6296.5592408530965;6301.6534090625455;6306.927822270225;6309.968450157719;6165.660888651168;6184.196054512884;6195.349488303663;6195.411435913581;6223.439890409885;6121.5139442231075;6125.632462052202;6191.907121393183;6248.094026366242;6258.718357673915;6168.845778855564;6187.16473276111;6188.44304012632;6217.533684973645;6246;6160.414760597736;6226.25284965807;6241.907666158404;6250.406211409909;6257;4837.2260438329695;5018.096742586296;5029.67307125043;5030.993801239767;5066.746662666852;4434.600885920283;4445.222086134913;4460.353248778909;4483.843065492757;4614.123316569841;5836.649801011953;5926.140709597084;5961.910571341434;5962.552808539377;5990.520758339322;6262.843428914313;6265.843353916189;6269.686515674198;6274.717637706314;6277.2153480814895;5791.305043394736;5802.970985145047;5808.6224395414165;5821.94178058214;5890.9410905891245;5376.086065368897;5426.511613954763;5436.320459942507;5459.962607104638;5475.260839786658;6176.69116544162;6236.376362363818;6238.906416403848;6244.1570387997035;6278.340774218641;6123.203983482119;6128.105930682329;6170.413810687977;6181.227346581677;6225.439710426083;6272.2159730033745;6274.811755647338;6274.811755647338;6293.024581189923;6308.716107775253;6117.857853872152;6157.291911430064;6214.533909956681;6230.127782110599;6239.96880015597;6145.72344244519;6163.568550201443;6165.260168779775;6241.500679945684;6246.718897649707;5066.240063990431;6126.417990290915;6157.322694503511;6169.444749972524;6189.3501182377;4539.455265368177;6016.368281330572;6144.1398204250545;6190.845228869314;6227.595206311576;4957.504249575014;5943;6065.120557519047;6112.480439162721;6120.9652521188555;6150.507959363151;6186.876262474815;6206.844828879314;6209.099680546386;6290.339514351111;4682.321063445849;4776;6113.84715382119;6139.3553676863285;6142.969285153546 06:36:54 INFO - PROCESS | 1950 | |1;ext.html;448.82047181127547;488.30416656264816;530.933633295838;531.5349069564131;538.8248819133781;33167.66832331676;35821.74623888164;37486.812565937165;41642.58357416425;42186.789066054705;42550.7872460638;43056.569434305646;43059.78470107645;43065;43080;8338.83322333553;9610.759731006729;9759.463229522373;9776.266779991494;9812.018798120202;9566.091221333962;9622.470764107971;9729.416235025921;9765.707028789113;9766.853497197548;9726.854097188549;9734.318597698164;9742.51287435629;9760.707178784647;9767.560459779292;9650.372725772802;9690.224782017456;9719.36824106438;9722.854157187612;9727.416355018722;5508.669479831183;5513.476219759116;5558.055130627803;5581.274434323553;5582;40162.79918600417;40231.59768402308;40446.59553404458;40478.59521404807;40549.594504054876;3238.611366636019;3246.2371342734436;3285.5728755261903;3308.7353011759;3362.1426536233103;30538.236544086576;30815.69184308173;30907.53638695399;31115.066548003597;32232;3217.3584003823853;3234.838258087086;3267.477203647405;3278.655741147169;3315.8010519368913;512821;520184;520493.3975330137;523244;525905;45756.542434575895;54932.90134197374;59925.40074599198;60015.699921500986;60025.699871500365;61456.385436145065;61474.692626536584;61919.49845266182;61979.380206198264;62132;16115.758263626056;16352.672946541003;16374;16398.26207820651;16413.507594772174;12600;12625.558105466265;12657.114002019955;12677.049221308438;12692;13618.455261789615;16725.996440213625;16778.161091945352;16919.91540042292;16999.32002719902;6695.933040669628;6704.899426508607;6739.123913891261;6742.83142921421;6811.557248778813;857.9099194584603;971.820213260549;992.925530585209;1005.7234260578399;1058.4284486377423;25764.86962421248;27351.863240684066;28894.42211155765;30554.38891222163;30868.382632347228;1035.6478797208833;1084.3060441317564;1091.5306418240234;1102.7408558988632;1119.8040342939953;15475.148866812207;15521.534353969399;15526.301316440788;15543.766843497357;15807.051576905422;643774;655999;658025;659620;662053;57336;57409.27772166842;57604;57742;57963.710181449664;1083.853679753239;1191.7139886427194;1332.0409305300168;1348.8786009259018;1367.254846108893;29088.7091129086;29935;30162.201296222716;30296;30515;49800.750996246235;50125.49874501208;50150.74924625354;50203.49796501988;50242;594912;595576.0221198866;596900;601008;602617;39883.80058099691;40269.731218539084;40479.392809107296;41177.1764564713;41324.38013429861;45760.855978600805;46250.7687461574;47430.52569474261;47778;48899.02201956012;32896.84861029948;39324.606753932094;39362.606373935894;39572.61495847631;41070.17859642731;38307;38854.805725971186;39132.80433597814;39928;40071;26256.60615090737;28771.136865894056;33313.83343083269;37065.81467092647;38322.23355532818;32582;35391.55578962028;35596.82201588975;35703.82148089243;36025;25296.873515632305;25630.487390251717;25690.486190275715;26163.345916352242;26237.868810655826 06:36:54 INFO - PROCESS | 1950 | |2;jquery.html;365.8042947023343;370.4776265465696;372.7297709160858;379.21124061951133;396.191768791665;16461.83538164618;19452.610947781053;20623.38129856103;20844.68732969005;21001.264955726554;19587.60824783507;19930.70103948442;20525;20687.896560517176;20988.37034888952;4907.75461226939;5195.714235717024;5895.592465804464;6092.543059270551;6154;5972.19375384324;6106.419890110433;6118.969405152968;6119.755209791625;6120.296165940907;5792;6020.397960203966;6063.939360606381;6071.757129714806;6072.7874524391655;4848.393950756155;5259.790248242906;5438.129899216126;6013.488853447462;6058.363871793443;4406.779661016968;5416.702081385522;5462.0987537056335;5467.835964921059;5500.614956953015;12838.486460541568;13126.671833204246;14835.925820370829;24758.504829903482;25117.874410628014;1785.8383022630535;2046.549759053006;2074;2369.7867191952805;2604.6874375075113;9721.222302215805;9792.21662267017;9844.114029737286;10148.340357876714;10402.895971040269;1759.384215524569;1800.4328636479468;1848.6210326882956;1850.722391641251;1876.1088482970479;109253;112586.43706781496;113230.43384783105;126187.5956125707;134535.32732336276;8796.47221166732;9209.585568649292;9763.658271960447;9765.902340976641;9888.653897113567;12128.513654700942;12201.450934707782;12281.93859030717;12289.69275768113;12311.4459849307;7927.960360198162;8039;8050.27547520726;8116.6347514362;8164.591770411455;6378.2346118466085;6939.479539034593;7142.678579463936;8400.411971162082;8461.619227134794;3767.962320376761;3847.1920896612023;3853.653171214548;3856.768593884376;3919.529656441245;1734.7658066160907;1881.3415304643265;1935.2549268531436;1936.06101040995;1971.1031480676545;792.952422854619;802.4984384759525;859.9398042137116;898.9640414383482;928.917407991872;19944.401667949984;20829;20959.790402095783;21190.258340957993;21378.78621213768;885.8095509465496;906.1708536688856;906.3927168796922;910.8406028944908;934.0985948559672;10617.522211500502;11018.50416731249;11095.50070246841;11112.944435277772;11140;604599;608832;621344;632530;633446;114251;121576;137278;139436.30281848524;145027;420.4639085166376;449.66499957531846;450.2323538367076;452.4096054648649;458.53229705700664;10603.840942385716;10760.085392741705;10905;11208.27146235492;11482.94258528702;45822.77088614536;46190.76904615456;53564.46435535751;54048;54479.910401792535;74593.25406745863;82207;82651.5867420659;83292;83882;11238.26951248199;11291.661250162508;11411.291529855362;11740.530378784753;11950.103742219195;11302.660920172408;11737.002354799595;12673.873261267638;12918.418671159632;13430.39563219638;9261.953690231505;9272;11078.335299882034;11147.386893720923;11657.417129143678;11092.944535277595;11108.166887483632;11338.943305283421;11643.708907277385;11785.88214117848;11170;11337.351685809108;11501.48243329069;11631.88368116342;11804.76390472203;9496.904907048265;11109.944555267237;11956.282623042645;12021.579244726743;12163.756724865276;9534.666286679932;11362.431878406212;11425.828612570984;11858.644240672793;11863.525458981889 06:36:54 INFO - PROCESS | 1950 | |3;mootools.html;1326.7014921642628;1505.8870584706146;1678.2699525706312;1799.7390378395125;1864.9254029838808;8091.838163236746;8366.707165249218;8627.498025572584;9212;9218.35471516994;7172.9282707172915;8910;8913.777155571113;8933.15135062168;8982.19160275575;2353.070537137834;2360.752121027293;2380.238323736405;2381.20229723043;2385.988070059656;2293;2345.9296221113395;2367.981767839828;2393.74865639108;2393.9880300598475;2345.319857241398;2374.3470545599926;2376.2039716694926;2392.605220138675;2396.9880150599224;2319.5592837360846;2323.349462150592;2328.69726935499;2330.417395651087;2336.988315058431;2331.895064722092;2335.8948847301913;2339.9649005264764;2344.0858065354523;2354.3054798834387;8923.107689231025;9030.367874248805;9042.276617870555;9095.499747513884;9131.497767622777;5225.581953443753;5314.335708036496;5320.334958130234;5394.568434525228;5413.756380962866;3140.795848269855;3602.873899413534;3651.7078633709507;3687.115092377837;3772.9056773580605;794.9046114466244;1082.6643740440488;1084.5553323137556;1088.9891101088967;1095.189559725805;9766.902330976742;11744;11755;11870.762584748343;11925.344106074162;4537.886552836206;4576.427946506687;4774.570288674036;4774.785134668949;4800.4719480857075;10085.899141008642;10086.44524551142;10129.848052279222;10180.592776288866;10196.133328666998;5355.919661205085;5356.330458692663;5369.328833895763;5371.274877891513;5378.462153784669;4391.099824535962;4572.51988541205;4663.230566956482;4890.339804126468;4921.286413470027;3825.445310429972;3859.4017927221325;3952.644262016375;4032.2741906457118;4040.252553277653;1261.2621616354536;1288.317191888282;1291.2833377475404;1291.5544137272625;1310.8689131086815;789.2856964447185;857.661223816589;914.849049906758;916.7295647783786;916.812053529025;4794.688345257547;5641.26663533738;5827.213326200994;6012.789552365646;6103.664298463627;791.976240712768;902.8329758994607;947.1333729637305;965.88892277389;1002.2583288366702;3775.6601905828607;3949.940750888739;3974.3442332014893;4031.3751368537924;4067.9796601016806;10467;10871.565137394573;10902.400367979837;10932.344059356465;10942.124630029737;10616.787664246825;10656.467176641137;10659.360438373722;10835.729106772236;10880.020798128206;7018.298170182941;7548.471606987568;8234.464759790595;8308.833823323499;8325.333973282122;5098.413682426569;5100.750316172562;5744.511716504144;5830.154627579062;5933.406659334032;9593.42439453635;10154.898451015692;10159.187265018929;10229.641962531276;10243.7439064024;9817.361871478332;10428.687139385831;10444;10454.947725261325;10469.790604188025;6539.705713243011;6743.426808721314;6824.761133360308;7095.467839911924;7171.820704482429;8183.918160818554;8474.74575762728;8501.19238672325;8503.659853606034;8519.403641745266;7457.47797654159;7492.51298665585;7558.962205188939;7610.972518709903;7691.5769632670745;7283.198848126596;7378.262173782578;7410.962945185455;7484.101907771103;7529.171791103084;6001.549883758648;6908.861822763617;7086;7093.893591596027;7118.750843720445;4660.648411960494;4718.7168769873915;4923.236898280772;5067.391912970467;5072.746362681777;4961.776720047678;4987.750612469435;5061.367329083864;5118.513741194581;5188.092083885381 06:36:54 INFO - PROCESS | 1950 | |4;prototype.html;291.2442212458669;302.1871166561948;309.338016644381;310.04815217282953;315.745824611188;5260.736963151846;5408.864778380543;5503.064479038563;5580.162975553664;5663.207150998858;5406.9723853554915;5428.972855135728;5441.646292990963;5504.862378440531;5539.335279766434;3754.6057663945303;3970.5507489766173;4150.983009162745;4191.706580539363;4221.535631080578;4066.9533813855255;4228.154369126171;4232.15356928614;4239.8340456374435;4248.320268756999;4145.854895078673;4166.83332666693;4184.560621134783;4206.621404073633;4228.577142285778;4144.792760362;4194.937075943863;4204.66362690984;4234.534201237861;4253.2769429197115;3172.1435212492497;3653.1962968146963;3666.7249956253386;3678.393065144248;3679.0066681995977;5001.974990125026;5436.178880075179;5462.525118218059;5463.590230732671;5510;3547.6807087362004;4016.5180178378782;4490.046829629089;4718.169098540882;4825.1555977703765;5574.303212098488;5595.608307418483;5950.477636995871;5952.940470595282;5957.285125784891;3311.3542859142476;3533.628968958248;3938.369860822255;3943.191645712622;3960.5049368828895;4515.909681806378;6123.448889599913;6184.84537886552;6217.782377616762;6270.96864515679;4001.1797581495725;4003.3394489908837;4003.459532963013;4010.438538604598;4014.297497937849;3358.5465962094804;3824.2351529694174;3890.630390112935;4039.1921615676983;4067.776272305052;4349.673774466928;4762.499937506581;4762.666613337103;4771.665983381199;4793.23308270675;5122.206058061007;5933.465988061095;5954.434328738763;5982.341942386334;6000.369961154016;1109.8834622364698;1241.4413513918773;1333.3799783100906;1347.063790665478;1379.3930670504947;773.0144066315493;780.7696729464822;803.4857691077598;846.3736834742369;847.8982522097265;3891.8248678809523;3945.3292940199663;4525.895458611762;4539.75031373271;4614.907701845944;4172.7704976226;4841.830535931225;4998.550420378122;5025.291400923704;5039.79840806371;4089.815958281825;4420.58004489573;4604.743675598999;4612.677112602153;4626.976865115653;2205.933821985343;2231.877246751412;2384.3428816972387;2457.680501534825;2660.5124208864772;4575.107853968444;4584.724916505021;4828.961773218704;5130.510803047334;5182.766775495112;4303.978480107579;4422.452141750426;4473.724988378297;4477.171723231213;4811.439534744334;5062.215356619729;5683.914741278884;6034.275886893601;6130.75070212788;6245.718942647501;5042.747862606929;5806.709664516673;5928.525717942468;6138.508919286357;6142;5650.85872853182;6062.6665533394325;6081.331053584191;6110.480609148272;6117;5188.351456067991;5427.430119837518;6189.81430557084;6230.345813689679;6233.625982441068;3969.2656858481273;4026.651071890832;4029.1941611677194;4032.9395059074723;4036.515618125726;5551.500364967173;5714.142928535732;5964.270361595089;6108.35862234459;6153.72308246139;5004.649674522749;5714.571407144397;5798.971005144947;5929.703514824155;5932.16949627044;4754.049190162051;4853.635977301646;4983.476734942778;5940.108983652487;5943.197668314722;5064.0756512525;5360.383555891122;5473.480019398151;5786.826395208151;5795.420457954171;5128.692278463304;5874.882502350015;5885.911711324419;5890.823275301748;6077.878442431038;5276.683398996072;5511.917321240265;5789.565782566408;5828.679422631796;5833.1250312451775 06:36:54 INFO - PROCESS | 1950 | |5;yui.html;675.4225137507431;699.3565919354197;737.524296828546;739.4047791527821;779.8128449172196;15085.92457037716;15335.619794218517;15630.3747850086;15738.685226295467;15777.526674199778;14338.713225735479;14510.27448627567;14531.273436328194;14541.854581454183;14584.343704533296;817.3869597801634;826.6817275348974;848.4569875279826;856.2892798976866;859.7119964811795;686.9347411995852;692.6640579319027;836.3977935886182;842.6839935024366;851.0638297872322;862.8490014247513;904.683360823713;908.4140729229642;908.9818203635923;909.5315912305168;897.5198268926093;901.0718959471754;901.2879824938357;901.995490022549;902.6795487601905;658.2857599504543;669.2638098092109;673.0745225315192;673.4343151752552;673.7709178879155;14334.42662293507;14577;14771.630709232355;14785.408583656641;14985.02597331181;14330;14536.563903082819;14554.927225363912;14744.967852250347;14892.62768430787;11795.705107372298;11843.881561184364;11903.16677832552;11944.761104777943;12126.151169418144;3767.5478942526797;3775.263823554408;3838.9808050959846;3839.4816699745456;3853.3064048471265;107480.4625976865;109880;113989;115569.4221528887;117120;11058.11535077192;11204.271722338184;11224.095765220736;11255.831162532404;11443.0273426758;23030.963606637742;23036.76963230346;23037.88481057584;23085.96113174911;23121.768782311963;7808.414368922354;7917.485363451357;7963.124056353912;7991.44059915812;8140.796480088045;4317.546657600907;4382.298832186835;4386.8025938832825;4473.083017981306;4527.411436513232;3450.879219227315;3588.9102772430897;3593.7304702147444;3599.1901822090344;3627.1657518770303;546.4453579616692;623.7473823101651;631.5926227583183;644.9613023218621;652.3085529338916;509.6519705379325;567.3900556901348;651.4234902111665;661.8146918862726;707.5507053021265;8466.703665371806;9263.90736092644;9398.859017114748;9579.185769209556;9625.278104142217;592.0704493944465;629.9008230637567;645.2998496631208;645.8546826963895;668.9364510371507;3786.769299977518;4721.15019296523;4732.479427262998;4768.451628062817;4817.253325734447;99735.50132249437;147003;150773;166539;169946;91176;91687;125189;146760;150834.24582877016;114657.4267128659;117466.41266793782;117605.41197294129;118930.21604675936;119728.4013579944;9055.501947392957;9070.954645226731;9130.49782261982;9138.588763505526;9157.542122893696;64949.67525162533;65994.34005659881;66603.33396665972;67037;67911.3208867905;63905;67656.98514522385;81746.18253817386;84696.57651711702;96364;13419.86580134186;13863.168209907439;14240.430382784987;14259.42962281497;14475.203863787592;14618.195999219719;14757.483488077865;15476.535703928901;15532.922335388703;15597.844021559638;13613.047086703835;14014.509492167725;14015.439382424589;14284.928575357057;14427.206503642401;13745.24401157946;13833.170009799444;13965.720685586028;14043.016988810685;14462.639260000233;13375.264360460269;13439.529616463376;14005.369758361103;14107.576772696835;14267;11120.499577518869;11324.660260192208;11328.376939268419;11475.540978361107;11764.941155295723;9742.659006934724;10305.330153539995;10672.946635266773;11575.421228938689;11713.590024349107 06:36:54 INFO - PROCESS | 1950 | __end_tp_report 06:36:54 INFO - PROCESS | 1950 | __start_cc_report 06:36:54 INFO - PROCESS | 1950 | _x_x_mozilla_cycle_collect,358 06:36:54 INFO - PROCESS | 1950 | __end_cc_report 06:36:54 INFO - PROCESS | 1950 | __startTimestamp1475674614719__endTimestamp 06:36:54 INFO - PROCESS | 1950 | 06:36:54 INFO - PROCESS | 1950 | ------- Summary: start ------- 06:36:54 INFO - PROCESS | 1950 | Number of tests: 6 06:36:54 INFO - PROCESS | 1950 | 06:36:54 INFO - PROCESS | 1950 | [#0] dojo.html Cycles:165 Average:5732.65 Median:6065.12 stddev:662.56 (10.9%) stddev-sans-first:638.41 06:36:54 INFO - PROCESS | 1950 | Values: 3381.8 3394.3 3404.9 3419.1 3424.3 5656.9 5710.5 5761.0 5810.6 5843.8 5696.9 5739.7 5768.5 5798.5 5812.0 5207.1 5221.3 5283.2 5325.5 5347.3 5108.5 5255.6 5341.8 5390.2 5399.3 5121.6 5176.8 5228.1 5239.9 5260.5 4530.8 5022.7 5168.1 5231.3 5286.7 4283.3 5183.1 5183.9 5214.7 5276.9 5579.6 5744.6 5763.9 5780.2 5828.2 6143.2 6197.7 6220.3 6249.5 6259.9 6232.0 6260.6 6268.9 6268.9 6294.7 5205.9 5589.0 5806.7 5834.2 5967.6 6198.9 6296.6 6301.7 6306.9 6310.0 6165.7 6184.2 6195.3 6195.4 6223.4 6121.5 6125.6 6191.9 6248.1 6258.7 6168.8 6187.2 6188.4 6217.5 6246.0 6160.4 6226.3 6241.9 6250.4 6257.0 4837.2 5018.1 5029.7 5031.0 5066.7 4434.6 4445.2 4460.4 4483.8 4614.1 5836.6 5926.1 5961.9 5962.6 5990.5 6262.8 6265.8 6269.7 6274.7 6277.2 5791.3 5803.0 5808.6 5821.9 5890.9 5376.1 5426.5 5436.3 5460.0 5475.3 6176.7 6236.4 6238.9 6244.2 6278.3 6123.2 6128.1 6170.4 6181.2 6225.4 6272.2 6274.8 6274.8 6293.0 6308.7 6117.9 6157.3 6214.5 6230.1 6240.0 6145.7 6163.6 6165.3 6241.5 6246.7 5066.2 6126.4 6157.3 6169.4 6189.4 4539.5 6016.4 6144.1 6190.8 6227.6 4957.5 5943.0 6065.1 6112.5 6121.0 6150.5 6186.9 6206.8 6209.1 6290.3 4682.3 4776.0 6113.8 6139.4 6143.0 06:36:54 INFO - PROCESS | 1950 | 06:36:54 INFO - PROCESS | 1950 | [#1] ext.html Cycles:180 Average:72018.68 Median:40514.49 stddev:158916.83 (392.2%) stddev-sans-first:159271.78 06:36:54 INFO - PROCESS | 1950 | Values: 448.8 488.3 530.9 531.5 538.8 33167.7 35821.7 37486.8 41642.6 42186.8 42550.8 43056.6 43059.8 43065.0 43080.0 8338.8 9610.8 9759.5 9776.3 9812.0 9566.1 9622.5 9729.4 9765.7 9766.9 9726.9 9734.3 9742.5 9760.7 9767.6 9650.4 9690.2 9719.4 9722.9 9727.4 5508.7 5513.5 5558.1 5581.3 5582.0 40162.8 40231.6 40446.6 40478.6 40549.6 3238.6 3246.2 3285.6 3308.7 3362.1 30538.2 30815.7 30907.5 31115.1 32232.0 3217.4 3234.8 3267.5 3278.7 3315.8 512821.0 520184.0 520493.4 523244.0 525905.0 45756.5 54932.9 59925.4 60015.7 60025.7 61456.4 61474.7 61919.5 61979.4 62132.0 16115.8 16352.7 16374.0 16398.3 16413.5 12600.0 12625.6 12657.1 12677.0 12692.0 13618.5 16726.0 16778.2 16919.9 16999.3 6695.9 6704.9 6739.1 6742.8 6811.6 857.9 971.8 992.9 1005.7 1058.4 25764.9 27351.9 28894.4 30554.4 30868.4 1035.6 1084.3 1091.5 1102.7 1119.8 15475.1 15521.5 15526.3 15543.8 15807.1 643774.0 655999.0 658025.0 659620.0 662053.0 57336.0 57409.3 57604.0 57742.0 57963.7 1083.9 1191.7 1332.0 1348.9 1367.3 29088.7 29935.0 30162.2 30296.0 30515.0 49800.8 50125.5 50150.7 50203.5 50242.0 594912.0 595576.0 596900.0 601008.0 602617.0 39883.8 40269.7 40479.4 41177.2 41324.4 45760.9 46250.8 47430.5 47778.0 48899.0 32896.8 39324.6 39362.6 39572.6 41070.2 38307.0 38854.8 39132.8 39928.0 40071.0 26256.6 28771.1 33313.8 37065.8 38322.2 32582.0 35391.6 35596.8 35703.8 36025.0 25296.9 25630.5 25690.5 26163.3 26237.9 06:36:54 INFO - PROCESS | 1950 | 06:36:54 INFO - PROCESS | 1950 | [#2] jquery.html Cycles:180 Average:35331.09 Median:24938.19 stddev:103587.32 (415.4%) stddev-sans-first:103844.63 06:36:54 INFO - PROCESS | 1950 | Values: 365.8 370.5 372.7 379.2 396.2 16461.8 19452.6 20623.4 20844.7 21001.3 19587.6 19930.7 20525.0 20687.9 20988.4 4907.8 5195.7 5895.6 6092.5 6154.0 5972.2 6106.4 6119.0 6119.8 6120.3 5792.0 6020.4 6063.9 6071.8 6072.8 4848.4 5259.8 5438.1 6013.5 6058.4 4406.8 5416.7 5462.1 5467.8 5500.6 12838.5 13126.7 14835.9 24758.5 25117.9 1785.8 2046.5 2074.0 2369.8 2604.7 9721.2 9792.2 9844.1 10148.3 10402.9 1759.4 1800.4 1848.6 1850.7 1876.1 109253.0 112586.4 113230.4 126187.6 134535.3 8796.5 9209.6 9763.7 9765.9 9888.7 12128.5 12201.5 12281.9 12289.7 12311.4 7928.0 8039.0 8050.3 8116.6 8164.6 6378.2 6939.5 7142.7 8400.4 8461.6 3768.0 3847.2 3853.7 3856.8 3919.5 1734.8 1881.3 1935.3 1936.1 1971.1 793.0 802.5 859.9 899.0 928.9 19944.4 20829.0 20959.8 21190.3 21378.8 885.8 906.2 906.4 910.8 934.1 10617.5 11018.5 11095.5 11112.9 11140.0 604599.0 608832.0 621344.0 632530.0 633446.0 114251.0 121576.0 137278.0 139436.3 145027.0 420.5 449.7 450.2 452.4 458.5 10603.8 10760.1 10905.0 11208.3 11482.9 45822.8 46190.8 53564.5 54048.0 54479.9 74593.3 82207.0 82651.6 83292.0 83882.0 11238.3 11291.7 11411.3 11740.5 11950.1 11302.7 11737.0 12673.9 12918.4 13430.4 9262.0 9272.0 11078.3 11147.4 11657.4 11092.9 11108.2 11338.9 11643.7 11785.9 11170.0 11337.4 11501.5 11631.9 11804.8 9496.9 11109.9 11956.3 12021.6 12163.8 9534.7 11362.4 11425.8 11858.6 11863.5 06:36:54 INFO - PROCESS | 1950 | 06:36:54 INFO - PROCESS | 1950 | [#3] mootools.html Cycles:180 Average:5697.35 Median:4784.74 stddev:3218.13 (67.3%) stddev-sans-first:3210.40 06:36:54 INFO - PROCESS | 1950 | Values: 1326.7 1505.9 1678.3 1799.7 1864.9 8091.8 8366.7 8627.5 9212.0 9218.4 7172.9 8910.0 8913.8 8933.2 8982.2 2353.1 2360.8 2380.2 2381.2 2386.0 2293.0 2345.9 2368.0 2393.7 2394.0 2345.3 2374.3 2376.2 2392.6 2397.0 2319.6 2323.3 2328.7 2330.4 2337.0 2331.9 2335.9 2340.0 2344.1 2354.3 8923.1 9030.4 9042.3 9095.5 9131.5 5225.6 5314.3 5320.3 5394.6 5413.8 3140.8 3602.9 3651.7 3687.1 3772.9 794.9 1082.7 1084.6 1089.0 1095.2 9766.9 11744.0 11755.0 11870.8 11925.3 4537.9 4576.4 4774.6 4774.8 4800.5 10085.9 10086.4 10129.8 10180.6 10196.1 5355.9 5356.3 5369.3 5371.3 5378.5 4391.1 4572.5 4663.2 4890.3 4921.3 3825.4 3859.4 3952.6 4032.3 4040.3 1261.3 1288.3 1291.3 1291.6 1310.9 789.3 857.7 914.8 916.7 916.8 4794.7 5641.3 5827.2 6012.8 6103.7 792.0 902.8 947.1 965.9 1002.3 3775.7 3949.9 3974.3 4031.4 4068.0 10467.0 10871.6 10902.4 10932.3 10942.1 10616.8 10656.5 10659.4 10835.7 10880.0 7018.3 7548.5 8234.5 8308.8 8325.3 5098.4 5100.8 5744.5 5830.2 5933.4 9593.4 10154.9 10159.2 10229.6 10243.7 9817.4 10428.7 10444.0 10454.9 10469.8 6539.7 6743.4 6824.8 7095.5 7171.8 8183.9 8474.7 8501.2 8503.7 8519.4 7457.5 7492.5 7559.0 7611.0 7691.6 7283.2 7378.3 7411.0 7484.1 7529.2 6001.5 6908.9 7086.0 7093.9 7118.8 4660.6 4718.7 4923.2 5067.4 5072.7 4961.8 4987.8 5061.4 5118.5 5188.1 06:36:54 INFO - PROCESS | 1950 | 06:36:54 INFO - PROCESS | 1950 | [#4] prototype.html Cycles:180 Average:4545.58 Median:4835.40 stddev:1450.28 (30.0%) stddev-sans-first:1418.76 06:36:54 INFO - PROCESS | 1950 | Values: 291.2 302.2 309.3 310.0 315.7 5260.7 5408.9 5503.1 5580.2 5663.2 5407.0 5429.0 5441.6 5504.9 5539.3 3754.6 3970.6 4151.0 4191.7 4221.5 4067.0 4228.2 4232.2 4239.8 4248.3 4145.9 4166.8 4184.6 4206.6 4228.6 4144.8 4194.9 4204.7 4234.5 4253.3 3172.1 3653.2 3666.7 3678.4 3679.0 5002.0 5436.2 5462.5 5463.6 5510.0 3547.7 4016.5 4490.0 4718.2 4825.2 5574.3 5595.6 5950.5 5952.9 5957.3 3311.4 3533.6 3938.4 3943.2 3960.5 4515.9 6123.4 6184.8 6217.8 6271.0 4001.2 4003.3 4003.5 4010.4 4014.3 3358.5 3824.2 3890.6 4039.2 4067.8 4349.7 4762.5 4762.7 4771.7 4793.2 5122.2 5933.5 5954.4 5982.3 6000.4 1109.9 1241.4 1333.4 1347.1 1379.4 773.0 780.8 803.5 846.4 847.9 3891.8 3945.3 4525.9 4539.8 4614.9 4172.8 4841.8 4998.6 5025.3 5039.8 4089.8 4420.6 4604.7 4612.7 4627.0 2205.9 2231.9 2384.3 2457.7 2660.5 4575.1 4584.7 4829.0 5130.5 5182.8 4304.0 4422.5 4473.7 4477.2 4811.4 5062.2 5683.9 6034.3 6130.8 6245.7 5042.7 5806.7 5928.5 6138.5 6142.0 5650.9 6062.7 6081.3 6110.5 6117.0 5188.4 5427.4 6189.8 6230.3 6233.6 3969.3 4026.7 4029.2 4032.9 4036.5 5551.5 5714.1 5964.3 6108.4 6153.7 5004.6 5714.6 5799.0 5929.7 5932.2 4754.0 4853.6 4983.5 5940.1 5943.2 5064.1 5360.4 5473.5 5786.8 5795.4 5128.7 5874.9 5885.9 5890.8 6077.9 5276.7 5511.9 5789.6 5828.7 5833.1 06:36:54 INFO - PROCESS | 1950 | 06:36:54 INFO - PROCESS | 1950 | [#5] yui.html Cycles:180 Average:24780.72 Median:3697.36 stddev:39561.88 (1070.0%) stddev-sans-first:39631.46 06:36:54 INFO - PROCESS | 1950 | Values: 675.4 699.4 737.5 739.4 779.8 15085.9 15335.6 15630.4 15738.7 15777.5 14338.7 14510.3 14531.3 14541.9 14584.3 817.4 826.7 848.5 856.3 859.7 686.9 692.7 836.4 842.7 851.1 862.8 904.7 908.4 909.0 909.5 897.5 901.1 901.3 902.0 902.7 658.3 669.3 673.1 673.4 673.8 14334.4 14577.0 14771.6 14785.4 14985.0 14330.0 14536.6 14554.9 14745.0 14892.6 11795.7 11843.9 11903.2 11944.8 12126.2 3767.5 3775.3 3839.0 3839.5 3853.3 107480.5 109880.0 113989.0 115569.4 117120.0 11058.1 11204.3 11224.1 11255.8 11443.0 23031.0 23036.8 23037.9 23086.0 23121.8 7808.4 7917.5 7963.1 7991.4 8140.8 4317.5 4382.3 4386.8 4473.1 4527.4 3450.9 3588.9 3593.7 3599.2 3627.2 546.4 623.7 631.6 645.0 652.3 509.7 567.4 651.4 661.8 707.6 8466.7 9263.9 9398.9 9579.2 9625.3 592.1 629.9 645.3 645.9 668.9 3786.8 4721.2 4732.5 4768.5 4817.3 99735.5 147003.0 150773.0 166539.0 169946.0 91176.0 91687.0 125189.0 146760.0 150834.2 114657.4 117466.4 117605.4 118930.2 119728.4 9055.5 9071.0 9130.5 9138.6 9157.5 64949.7 65994.3 66603.3 67037.0 67911.3 63905.0 67657.0 81746.2 84696.6 96364.0 13419.9 13863.2 14240.4 14259.4 14475.2 14618.2 14757.5 15476.5 15532.9 15597.8 13613.0 14014.5 14015.4 14284.9 14427.2 13745.2 13833.2 13965.7 14043.0 14462.6 13375.3 13439.5 14005.4 14107.6 14267.0 11120.5 11324.7 11328.4 11475.5 11764.9 9742.7 10305.3 10672.9 11575.4 11713.6 06:36:54 INFO - PROCESS | 1950 | -------- Summary: end -------- 06:36:54 INFO - PROCESS | 1950 | 06:36:55 INFO - TEST-INFO | 1950: exit 0 06:36:55 INFO - TEST-OK | dromaeo_css | took 1115789ms 06:36:55 INFO - TEST-START | kraken 06:36:55 INFO - operating with platform_type : mac_ 06:36:55 INFO - Initialising browser for kraken test... 06:36:55 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmptZ16Gh/profile http://localhost:55808/getInfo.html) 06:36:57 INFO - PROCESS | 1955 | 2016-10-05 06:36:57.944 plugin-container[1957:22067] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461f, name = 'com.apple.tsm.portname' 06:36:57 INFO - PROCESS | 1955 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:36:57 INFO - PROCESS | 1955 | 2016-10-05 06:36:57.944 plugin-container[1957:22067] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 06:36:57 INFO - PROCESS | 1955 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:36:57 INFO - PROCESS | 1955 | 2016-10-05 06:36:57.945 plugin-container[1957:22067] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:36:57 INFO - PROCESS | 1955 | __metrics Screen width/height:1600/1200 06:36:57 INFO - PROCESS | 1955 | colorDepth:24 06:36:57 INFO - PROCESS | 1955 | Browser inner width/height: 1024/689 06:36:57 INFO - PROCESS | 1955 | __metrics 06:36:58 ERROR - PROCESS | 1955 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 06:36:58 INFO - TEST-INFO | 1955: exit 0 06:36:58 INFO - Browser initialized. 06:36:58 INFO - Running cycle 1/1 for kraken test... 06:36:58 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmptZ16Gh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:37:01 INFO - PROCESS | 1958 | 2016-10-05 06:37:01.394 plugin-container[1959:22179] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x891b, name = 'com.apple.tsm.portname' 06:37:01 INFO - PROCESS | 1958 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:37:01 INFO - PROCESS | 1958 | 2016-10-05 06:37:01.394 plugin-container[1959:22179] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 06:37:01 INFO - PROCESS | 1958 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:37:01 INFO - PROCESS | 1958 | 2016-10-05 06:37:01.394 plugin-container[1959:22179] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:37:10 INFO - PROCESS | 1958 | RSS: Main: 240152576 06:37:10 INFO - PROCESS | 1958 | 06:37:13 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:55808/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55808/tests/kraken/driver.html?testName=audio-beat-detection) 06:37:14 INFO - PROCESS | 1958 | RSS: Main: 253784064 06:37:14 INFO - PROCESS | 1958 | 06:37:17 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:55808/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55808/tests/kraken/driver.html?testName=audio-dft) 06:37:17 INFO - PROCESS | 1958 | RSS: Main: 261255168 06:37:17 INFO - PROCESS | 1958 | 06:37:21 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:55808/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55808/tests/kraken/driver.html?testName=audio-fft) 06:37:22 INFO - PROCESS | 1958 | RSS: Main: 261894144 06:37:22 INFO - PROCESS | 1958 | 06:37:24 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:55808/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55808/tests/kraken/driver.html?testName=audio-oscillator) 06:37:24 INFO - PROCESS | 1958 | RSS: Main: 263380992 06:37:24 INFO - PROCESS | 1958 | 06:37:26 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:55808/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55808/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:37:27 INFO - PROCESS | 1958 | RSS: Main: 264716288 06:37:27 INFO - PROCESS | 1958 | 06:37:30 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:55808/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55808/tests/kraken/driver.html?testName=imaging-darkroom) 06:37:30 INFO - PROCESS | 1958 | RSS: Main: 265474048 06:37:30 INFO - PROCESS | 1958 | 06:37:33 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:55808/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55808/tests/kraken/driver.html?testName=imaging-desaturate) 06:37:34 INFO - PROCESS | 1958 | RSS: Main: 265469952 06:37:34 INFO - PROCESS | 1958 | 06:37:37 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:55808/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55808/tests/kraken/driver.html?testName=json-parse-financial) 06:37:37 INFO - PROCESS | 1958 | RSS: Main: 265916416 06:37:38 INFO - PROCESS | 1958 | 06:37:39 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:55808/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55808/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:37:40 INFO - PROCESS | 1958 | RSS: Main: 265404416 06:37:40 INFO - PROCESS | 1958 | 06:37:42 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:55808/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55808/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:37:42 INFO - PROCESS | 1958 | RSS: Main: 265515008 06:37:42 INFO - PROCESS | 1958 | 06:37:45 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:55808/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55808/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:37:45 INFO - PROCESS | 1958 | RSS: Main: 265519104 06:37:45 INFO - PROCESS | 1958 | 06:37:48 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:55808/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55808/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:37:49 INFO - PROCESS | 1958 | RSS: Main: 265936896 06:37:49 INFO - PROCESS | 1958 | 06:37:52 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:55808/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55808/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:37:53 INFO - PROCESS | 1958 | RSS: Main: 265904128 06:37:53 INFO - PROCESS | 1958 | 06:37:54 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:55808/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55808/tests/kraken/driver.html?testName=ai-astar) 06:37:55 INFO - PROCESS | 1958 | RSS: Main: 267677696 06:37:55 INFO - PROCESS | 1958 | 06:37:55 INFO - PROCESS | 1958 | __start_tp_report 06:37:55 INFO - PROCESS | 1958 | _x_x_mozilla_page_load 06:37:55 INFO - PROCESS | 1958 | _x_x_mozilla_page_load_details 06:37:55 INFO - PROCESS | 1958 | |i|pagename|runs| 06:37:55 INFO - PROCESS | 1958 | |0;ai-astar;115;119;117;117;115;117;116;126;117;116 06:37:55 INFO - PROCESS | 1958 | |1;audio-beat-detection;157;118;115;156;117;147;147;118;146;115 06:37:55 INFO - PROCESS | 1958 | |2;audio-dft;270;198;187;212;209;280;278;229;197;206 06:37:55 INFO - PROCESS | 1958 | |3;audio-fft;82;59;59;85;64;77;85;88;94;58 06:37:55 INFO - PROCESS | 1958 | |4;audio-oscillator;83;70;80;66;73;71;89;69;72;87 06:37:55 INFO - PROCESS | 1958 | |5;imaging-gaussian-blur;105;87;90;86;89;85;108;82;124;115 06:37:55 INFO - PROCESS | 1958 | |6;imaging-darkroom;113;166;164;172;125;100;98;102;100;120 06:37:55 INFO - PROCESS | 1958 | |7;imaging-desaturate;91;95;100;92;116;145;89;94;92;91 06:37:55 INFO - PROCESS | 1958 | |8;json-parse-financial;57;56;59;67;82;88;86;49;53;54 06:37:55 INFO - PROCESS | 1958 | |9;json-stringify-tinderbox;68;76;94;71;81;111;77;63;85;72 06:37:55 INFO - PROCESS | 1958 | |10;stanford-crypto-aes;88;100;112;87;91;91;90;87;77;106 06:37:55 INFO - PROCESS | 1958 | |11;stanford-crypto-ccm;137;136;153;163;172;163;148;148;180;149 06:37:55 INFO - PROCESS | 1958 | |12;stanford-crypto-pbkdf2;172;170;174;173;178;193;249;236;171;173 06:37:55 INFO - PROCESS | 1958 | |13;stanford-crypto-sha256-iterative;71;71;69;69;70;68;70;72;68;67 06:37:55 INFO - PROCESS | 1958 | __end_tp_report 06:37:55 INFO - PROCESS | 1958 | __start_cc_report 06:37:55 INFO - PROCESS | 1958 | _x_x_mozilla_cycle_collect,922 06:37:55 INFO - PROCESS | 1958 | __end_cc_report 06:37:55 INFO - PROCESS | 1958 | __startTimestamp1475674675119__endTimestamp 06:37:55 INFO - PROCESS | 1958 | 06:37:55 INFO - PROCESS | 1958 | ------- Summary: start ------- 06:37:55 INFO - PROCESS | 1958 | Number of tests: 14 06:37:55 INFO - PROCESS | 1958 | 06:37:55 INFO - PROCESS | 1958 | [#0] ai-astar Cycles:10 Average:117.50 Median:117.00 stddev:3.21 (2.7%) stddev-sans-first:3.27 06:37:55 INFO - PROCESS | 1958 | Values: 115.0 119.0 117.0 117.0 115.0 117.0 116.0 126.0 117.0 116.0 06:37:55 INFO - PROCESS | 1958 | 06:37:55 INFO - PROCESS | 1958 | [#1] audio-beat-detection Cycles:10 Average:133.60 Median:146.50 stddev:18.31 (12.5%) stddev-sans-first:17.35 06:37:55 INFO - PROCESS | 1958 | Values: 157.0 118.0 115.0 156.0 117.0 147.0 147.0 118.0 146.0 115.0 06:37:55 INFO - PROCESS | 1958 | 06:37:55 INFO - PROCESS | 1958 | [#2] audio-dft Cycles:10 Average:226.60 Median:220.50 stddev:35.89 (16.3%) stddev-sans-first:34.46 06:37:55 INFO - PROCESS | 1958 | Values: 270.0 198.0 187.0 212.0 209.0 280.0 278.0 229.0 197.0 206.0 06:37:55 INFO - PROCESS | 1958 | 06:37:55 INFO - PROCESS | 1958 | [#3] audio-fft Cycles:10 Average:75.10 Median:83.50 stddev:13.76 (16.5%) stddev-sans-first:14.37 06:37:55 INFO - PROCESS | 1958 | Values: 82.0 59.0 59.0 85.0 64.0 77.0 85.0 88.0 94.0 58.0 06:37:55 INFO - PROCESS | 1958 | 06:37:55 INFO - PROCESS | 1958 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:76.50 stddev:8.10 (10.6%) stddev-sans-first:8.18 06:37:55 INFO - PROCESS | 1958 | Values: 83.0 70.0 80.0 66.0 73.0 71.0 89.0 69.0 72.0 87.0 06:37:55 INFO - PROCESS | 1958 | 06:37:55 INFO - PROCESS | 1958 | [#5] imaging-gaussian-blur Cycles:10 Average:97.10 Median:85.50 stddev:14.69 (17.2%) stddev-sans-first:15.30 06:37:55 INFO - PROCESS | 1958 | Values: 105.0 87.0 90.0 86.0 89.0 85.0 108.0 82.0 124.0 115.0 06:37:55 INFO - PROCESS | 1958 | 06:37:55 INFO - PROCESS | 1958 | [#6] imaging-darkroom Cycles:10 Average:126.00 Median:144.50 stddev:29.96 (20.7%) stddev-sans-first:31.41 06:37:55 INFO - PROCESS | 1958 | Values: 113.0 166.0 164.0 172.0 125.0 100.0 98.0 102.0 100.0 120.0 06:37:55 INFO - PROCESS | 1958 | 06:37:55 INFO - PROCESS | 1958 | [#7] imaging-desaturate Cycles:10 Average:100.50 Median:91.50 stddev:17.48 (19.1%) stddev-sans-first:18.20 06:37:55 INFO - PROCESS | 1958 | Values: 91.0 95.0 100.0 92.0 116.0 145.0 89.0 94.0 92.0 91.0 06:37:55 INFO - PROCESS | 1958 | 06:37:55 INFO - PROCESS | 1958 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:63.00 stddev:14.78 (23.5%) stddev-sans-first:15.38 06:37:55 INFO - PROCESS | 1958 | Values: 57.0 56.0 59.0 67.0 82.0 88.0 86.0 49.0 53.0 54.0 06:37:55 INFO - PROCESS | 1958 | 06:37:55 INFO - PROCESS | 1958 | [#9] json-stringify-tinderbox Cycles:10 Average:79.80 Median:76.50 stddev:14.09 (18.4%) stddev-sans-first:14.28 06:37:55 INFO - PROCESS | 1958 | Values: 68.0 76.0 94.0 71.0 81.0 111.0 77.0 63.0 85.0 72.0 06:37:55 INFO - PROCESS | 1958 | 06:37:55 INFO - PROCESS | 1958 | [#10] stanford-crypto-aes Cycles:10 Average:92.90 Median:87.50 stddev:10.27 (11.7%) stddev-sans-first:10.74 06:37:55 INFO - PROCESS | 1958 | Values: 88.0 100.0 112.0 87.0 91.0 91.0 90.0 87.0 77.0 106.0 06:37:55 INFO - PROCESS | 1958 | 06:37:55 INFO - PROCESS | 1958 | [#11] stanford-crypto-ccm Cycles:10 Average:154.90 Median:158.00 stddev:14.39 (9.1%) stddev-sans-first:13.73 06:37:55 INFO - PROCESS | 1958 | Values: 137.0 136.0 153.0 163.0 172.0 163.0 148.0 148.0 180.0 149.0 06:37:55 INFO - PROCESS | 1958 | 06:37:55 INFO - PROCESS | 1958 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.90 Median:176.00 stddev:29.17 (16.6%) stddev-sans-first:30.29 06:37:55 INFO - PROCESS | 1958 | Values: 172.0 170.0 174.0 173.0 178.0 193.0 249.0 236.0 171.0 173.0 06:37:55 INFO - PROCESS | 1958 | 06:37:55 INFO - PROCESS | 1958 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:70.00 stddev:1.58 (2.3%) stddev-sans-first:1.58 06:37:55 INFO - PROCESS | 1958 | Values: 71.0 71.0 69.0 69.0 70.0 68.0 70.0 72.0 68.0 67.0 06:37:55 INFO - PROCESS | 1958 | -------- Summary: end -------- 06:37:55 INFO - PROCESS | 1958 | 06:37:55 INFO - TEST-INFO | 1958: exit 0 06:37:55 INFO - TEST-OK | kraken | took 60604ms 06:37:55 INFO - SUITE-END | took 1176s 06:37:56 INFO - Completed test suite (00:19:36) 06:37:56 INFO - javascript benchmark 06:37:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7276.2240138475345, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3381.8309084545776, 3394.2871996880667, 3404.897853064407, 3419.1281223288056, 3424.332255210232, 5656.943430565693, 5710.457506536877, 5761.0, 5810.5932584719085, 5843.79546715865, 5696.914546281799, 5739.713014349276, 5768.509676677477, 5798.478136967672, 5812.0410132328225, 5207.0887594670785, 5221.295125158112, 5283.181106928432, 5325.4940780625975, 5347.25138480613, 5108.48915108488, 5255.5795536357, 5341.786328546854, 5390.164524498709, 5399.271098401705, 5121.641485096045, 5176.767045482962, 5228.058949389108, 5239.895202095937, 5260.473952604709, 4530.841420550265, 5022.698638081727, 5168.095583272912, 5231.346081739783, 5286.682799032071, 4283.250431174532, 5183.144781111112, 5183.870403239949, 5214.739263036833, 5276.947230527722, 5579.581531385163, 5744.597878148531, 5763.855903602402, 5780.1907732917425, 5828.184054232411, 6143.235544005171, 6197.690115494207, 6220.315765265817, 6249.500039996789, 6259.906101408483, 6232.034034724625, 6260.624362538262, 6268.87462250757, 6268.937310626881, 6294.653794041325, 5205.9219111713355, 5589.0, 5806.68063256525, 5834.183214349994, 5967.6121052131475, 6198.876022479525, 6296.5592408530965, 6301.6534090625455, 6306.927822270225, 6309.968450157719, 6165.660888651168, 6184.196054512884, 6195.349488303663, 6195.411435913581, 6223.439890409885, 6121.5139442231075, 6125.632462052202, 6191.907121393183, 6248.094026366242, 6258.718357673915, 6168.845778855564, 6187.16473276111, 6188.44304012632, 6217.533684973645, 6246.0, 6160.414760597736, 6226.25284965807, 6241.907666158404, 6250.406211409909, 6257.0, 4837.2260438329695, 5018.096742586296, 5029.67307125043, 5030.993801239767, 5066.746662666852, 4434.600885920283, 4445.222086134913, 4460.353248778909, 4483.843065492757, 4614.123316569841, 5836.649801011953, 5926.140709597084, 5961.910571341434, 5962.552808539377, 5990.520758339322, 6262.843428914313, 6265.843353916189, 6269.686515674198, 6274.717637706314, 6277.2153480814895, 5791.305043394736, 5802.970985145047, 5808.6224395414165, 5821.94178058214, 5890.9410905891245, 5376.086065368897, 5426.511613954763, 5436.320459942507, 5459.962607104638, 5475.260839786658, 6176.69116544162, 6236.376362363818, 6238.906416403848, 6244.1570387997035, 6278.340774218641, 6123.203983482119, 6128.105930682329, 6170.413810687977, 6181.227346581677, 6225.439710426083, 6272.2159730033745, 6274.811755647338, 6274.811755647338, 6293.024581189923, 6308.716107775253, 6117.857853872152, 6157.291911430064, 6214.533909956681, 6230.127782110599, 6239.96880015597, 6145.72344244519, 6163.568550201443, 6165.260168779775, 6241.500679945684, 6246.718897649707, 5066.240063990431, 6126.417990290915, 6157.322694503511, 6169.444749972524, 6189.3501182377, 4539.455265368177, 6016.368281330572, 6144.1398204250545, 6190.845228869314, 6227.595206311576, 4957.504249575014, 5943.0, 6065.120557519047, 6112.480439162721, 6120.9652521188555, 6150.507959363151, 6186.876262474815, 6206.844828879314, 6209.099680546386, 6290.339514351111, 4682.321063445849, 4776.0, 6113.84715382119, 6139.3553676863285, 6142.969285153546], "value": 5692.715470802336, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.82047181127547, 488.30416656264816, 530.933633295838, 531.5349069564131, 538.8248819133781, 33167.66832331676, 35821.74623888164, 37486.812565937165, 41642.58357416425, 42186.789066054705, 42550.7872460638, 43056.569434305646, 43059.78470107645, 43065.0, 43080.0, 8338.83322333553, 9610.759731006729, 9759.463229522373, 9776.266779991494, 9812.018798120202, 9566.091221333962, 9622.470764107971, 9729.416235025921, 9765.707028789113, 9766.853497197548, 9726.854097188549, 9734.318597698164, 9742.51287435629, 9760.707178784647, 9767.560459779292, 9650.372725772802, 9690.224782017456, 9719.36824106438, 9722.854157187612, 9727.416355018722, 5508.669479831183, 5513.476219759116, 5558.055130627803, 5581.274434323553, 5582.0, 40162.79918600417, 40231.59768402308, 40446.59553404458, 40478.59521404807, 40549.594504054876, 3238.611366636019, 3246.2371342734436, 3285.5728755261903, 3308.7353011759, 3362.1426536233103, 30538.236544086576, 30815.69184308173, 30907.53638695399, 31115.066548003597, 32232.0, 3217.3584003823853, 3234.838258087086, 3267.477203647405, 3278.655741147169, 3315.8010519368913, 512821.0, 520184.0, 520493.3975330137, 523244.0, 525905.0, 45756.542434575895, 54932.90134197374, 59925.40074599198, 60015.699921500986, 60025.699871500365, 61456.385436145065, 61474.692626536584, 61919.49845266182, 61979.380206198264, 62132.0, 16115.758263626056, 16352.672946541003, 16374.0, 16398.26207820651, 16413.507594772174, 12600.0, 12625.558105466265, 12657.114002019955, 12677.049221308438, 12692.0, 13618.455261789615, 16725.996440213625, 16778.161091945352, 16919.91540042292, 16999.32002719902, 6695.933040669628, 6704.899426508607, 6739.123913891261, 6742.83142921421, 6811.557248778813, 857.9099194584603, 971.820213260549, 992.925530585209, 1005.7234260578399, 1058.4284486377423, 25764.86962421248, 27351.863240684066, 28894.42211155765, 30554.38891222163, 30868.382632347228, 1035.6478797208833, 1084.3060441317564, 1091.5306418240234, 1102.7408558988632, 1119.8040342939953, 15475.148866812207, 15521.534353969399, 15526.301316440788, 15543.766843497357, 15807.051576905422, 643774.0, 655999.0, 658025.0, 659620.0, 662053.0, 57336.0, 57409.27772166842, 57604.0, 57742.0, 57963.710181449664, 1083.853679753239, 1191.7139886427194, 1332.0409305300168, 1348.8786009259018, 1367.254846108893, 29088.7091129086, 29935.0, 30162.201296222716, 30296.0, 30515.0, 49800.750996246235, 50125.49874501208, 50150.74924625354, 50203.49796501988, 50242.0, 594912.0, 595576.0221198866, 596900.0, 601008.0, 602617.0, 39883.80058099691, 40269.731218539084, 40479.392809107296, 41177.1764564713, 41324.38013429861, 45760.855978600805, 46250.7687461574, 47430.52569474261, 47778.0, 48899.02201956012, 32896.84861029948, 39324.606753932094, 39362.606373935894, 39572.61495847631, 41070.17859642731, 38307.0, 38854.805725971186, 39132.80433597814, 39928.0, 40071.0, 26256.60615090737, 28771.136865894056, 33313.83343083269, 37065.81467092647, 38322.23355532818, 32582.0, 35391.55578962028, 35596.82201588975, 35703.82148089243, 36025.0, 25296.873515632305, 25630.487390251717, 25690.486190275715, 26163.345916352242, 26237.868810655826], "value": 19681.436119857797, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.8042947023343, 370.4776265465696, 372.7297709160858, 379.21124061951133, 396.191768791665, 16461.83538164618, 19452.610947781053, 20623.38129856103, 20844.68732969005, 21001.264955726554, 19587.60824783507, 19930.70103948442, 20525.0, 20687.896560517176, 20988.37034888952, 4907.75461226939, 5195.714235717024, 5895.592465804464, 6092.543059270551, 6154.0, 5972.19375384324, 6106.419890110433, 6118.969405152968, 6119.755209791625, 6120.296165940907, 5792.0, 6020.397960203966, 6063.939360606381, 6071.757129714806, 6072.7874524391655, 4848.393950756155, 5259.790248242906, 5438.129899216126, 6013.488853447462, 6058.363871793443, 4406.779661016968, 5416.702081385522, 5462.0987537056335, 5467.835964921059, 5500.614956953015, 12838.486460541568, 13126.671833204246, 14835.925820370829, 24758.504829903482, 25117.874410628014, 1785.8383022630535, 2046.549759053006, 2074.0, 2369.7867191952805, 2604.6874375075113, 9721.222302215805, 9792.21662267017, 9844.114029737286, 10148.340357876714, 10402.895971040269, 1759.384215524569, 1800.4328636479468, 1848.6210326882956, 1850.722391641251, 1876.1088482970479, 109253.0, 112586.43706781496, 113230.43384783105, 126187.5956125707, 134535.32732336276, 8796.47221166732, 9209.585568649292, 9763.658271960447, 9765.902340976641, 9888.653897113567, 12128.513654700942, 12201.450934707782, 12281.93859030717, 12289.69275768113, 12311.4459849307, 7927.960360198162, 8039.0, 8050.27547520726, 8116.6347514362, 8164.591770411455, 6378.2346118466085, 6939.479539034593, 7142.678579463936, 8400.411971162082, 8461.619227134794, 3767.962320376761, 3847.1920896612023, 3853.653171214548, 3856.768593884376, 3919.529656441245, 1734.7658066160907, 1881.3415304643265, 1935.2549268531436, 1936.06101040995, 1971.1031480676545, 792.952422854619, 802.4984384759525, 859.9398042137116, 898.9640414383482, 928.917407991872, 19944.401667949984, 20829.0, 20959.790402095783, 21190.258340957993, 21378.78621213768, 885.8095509465496, 906.1708536688856, 906.3927168796922, 910.8406028944908, 934.0985948559672, 10617.522211500502, 11018.50416731249, 11095.50070246841, 11112.944435277772, 11140.0, 604599.0, 608832.0, 621344.0, 632530.0, 633446.0, 114251.0, 121576.0, 137278.0, 139436.30281848524, 145027.0, 420.4639085166376, 449.66499957531846, 450.2323538367076, 452.4096054648649, 458.53229705700664, 10603.840942385716, 10760.085392741705, 10905.0, 11208.27146235492, 11482.94258528702, 45822.77088614536, 46190.76904615456, 53564.46435535751, 54048.0, 54479.910401792535, 74593.25406745863, 82207.0, 82651.5867420659, 83292.0, 83882.0, 11238.26951248199, 11291.661250162508, 11411.291529855362, 11740.530378784753, 11950.103742219195, 11302.660920172408, 11737.002354799595, 12673.873261267638, 12918.418671159632, 13430.39563219638, 9261.953690231505, 9272.0, 11078.335299882034, 11147.386893720923, 11657.417129143678, 11092.944535277595, 11108.166887483632, 11338.943305283421, 11643.708907277385, 11785.88214117848, 11170.0, 11337.351685809108, 11501.48243329069, 11631.88368116342, 11804.76390472203, 9496.904907048265, 11109.944555267237, 11956.282623042645, 12021.579244726743, 12163.756724865276, 9534.666286679932, 11362.431878406212, 11425.828612570984, 11858.644240672793, 11863.525458981889], "value": 9065.823272660675, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1326.7014921642628, 1505.8870584706146, 1678.2699525706312, 1799.7390378395125, 1864.9254029838808, 8091.838163236746, 8366.707165249218, 8627.498025572584, 9212.0, 9218.35471516994, 7172.9282707172915, 8910.0, 8913.777155571113, 8933.15135062168, 8982.19160275575, 2353.070537137834, 2360.752121027293, 2380.238323736405, 2381.20229723043, 2385.988070059656, 2293.0, 2345.9296221113395, 2367.981767839828, 2393.74865639108, 2393.9880300598475, 2345.319857241398, 2374.3470545599926, 2376.2039716694926, 2392.605220138675, 2396.9880150599224, 2319.5592837360846, 2323.349462150592, 2328.69726935499, 2330.417395651087, 2336.988315058431, 2331.895064722092, 2335.8948847301913, 2339.9649005264764, 2344.0858065354523, 2354.3054798834387, 8923.107689231025, 9030.367874248805, 9042.276617870555, 9095.499747513884, 9131.497767622777, 5225.581953443753, 5314.335708036496, 5320.334958130234, 5394.568434525228, 5413.756380962866, 3140.795848269855, 3602.873899413534, 3651.7078633709507, 3687.115092377837, 3772.9056773580605, 794.9046114466244, 1082.6643740440488, 1084.5553323137556, 1088.9891101088967, 1095.189559725805, 9766.902330976742, 11744.0, 11755.0, 11870.762584748343, 11925.344106074162, 4537.886552836206, 4576.427946506687, 4774.570288674036, 4774.785134668949, 4800.4719480857075, 10085.899141008642, 10086.44524551142, 10129.848052279222, 10180.592776288866, 10196.133328666998, 5355.919661205085, 5356.330458692663, 5369.328833895763, 5371.274877891513, 5378.462153784669, 4391.099824535962, 4572.51988541205, 4663.230566956482, 4890.339804126468, 4921.286413470027, 3825.445310429972, 3859.4017927221325, 3952.644262016375, 4032.2741906457118, 4040.252553277653, 1261.2621616354536, 1288.317191888282, 1291.2833377475404, 1291.5544137272625, 1310.8689131086815, 789.2856964447185, 857.661223816589, 914.849049906758, 916.7295647783786, 916.812053529025, 4794.688345257547, 5641.26663533738, 5827.213326200994, 6012.789552365646, 6103.664298463627, 791.976240712768, 902.8329758994607, 947.1333729637305, 965.88892277389, 1002.2583288366702, 3775.6601905828607, 3949.940750888739, 3974.3442332014893, 4031.3751368537924, 4067.9796601016806, 10467.0, 10871.565137394573, 10902.400367979837, 10932.344059356465, 10942.124630029737, 10616.787664246825, 10656.467176641137, 10659.360438373722, 10835.729106772236, 10880.020798128206, 7018.298170182941, 7548.471606987568, 8234.464759790595, 8308.833823323499, 8325.333973282122, 5098.413682426569, 5100.750316172562, 5744.511716504144, 5830.154627579062, 5933.406659334032, 9593.42439453635, 10154.898451015692, 10159.187265018929, 10229.641962531276, 10243.7439064024, 9817.361871478332, 10428.687139385831, 10444.0, 10454.947725261325, 10469.790604188025, 6539.705713243011, 6743.426808721314, 6824.761133360308, 7095.467839911924, 7171.820704482429, 8183.918160818554, 8474.74575762728, 8501.19238672325, 8503.659853606034, 8519.403641745266, 7457.47797654159, 7492.51298665585, 7558.962205188939, 7610.972518709903, 7691.5769632670745, 7283.198848126596, 7378.262173782578, 7410.962945185455, 7484.101907771103, 7529.171791103084, 6001.549883758648, 6908.861822763617, 7086.0, 7093.893591596027, 7118.750843720445, 4660.648411960494, 4718.7168769873915, 4923.236898280772, 5067.391912970467, 5072.746362681777, 4961.776720047678, 4987.750612469435, 5061.367329083864, 5118.513741194581, 5188.092083885381], "value": 4571.609484453617, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.2442212458669, 302.1871166561948, 309.338016644381, 310.04815217282953, 315.745824611188, 5260.736963151846, 5408.864778380543, 5503.064479038563, 5580.162975553664, 5663.207150998858, 5406.9723853554915, 5428.972855135728, 5441.646292990963, 5504.862378440531, 5539.335279766434, 3754.6057663945303, 3970.5507489766173, 4150.983009162745, 4191.706580539363, 4221.535631080578, 4066.9533813855255, 4228.154369126171, 4232.15356928614, 4239.8340456374435, 4248.320268756999, 4145.854895078673, 4166.83332666693, 4184.560621134783, 4206.621404073633, 4228.577142285778, 4144.792760362, 4194.937075943863, 4204.66362690984, 4234.534201237861, 4253.2769429197115, 3172.1435212492497, 3653.1962968146963, 3666.7249956253386, 3678.393065144248, 3679.0066681995977, 5001.974990125026, 5436.178880075179, 5462.525118218059, 5463.590230732671, 5510.0, 3547.6807087362004, 4016.5180178378782, 4490.046829629089, 4718.169098540882, 4825.1555977703765, 5574.303212098488, 5595.608307418483, 5950.477636995871, 5952.940470595282, 5957.285125784891, 3311.3542859142476, 3533.628968958248, 3938.369860822255, 3943.191645712622, 3960.5049368828895, 4515.909681806378, 6123.448889599913, 6184.84537886552, 6217.782377616762, 6270.96864515679, 4001.1797581495725, 4003.3394489908837, 4003.459532963013, 4010.438538604598, 4014.297497937849, 3358.5465962094804, 3824.2351529694174, 3890.630390112935, 4039.1921615676983, 4067.776272305052, 4349.673774466928, 4762.499937506581, 4762.666613337103, 4771.665983381199, 4793.23308270675, 5122.206058061007, 5933.465988061095, 5954.434328738763, 5982.341942386334, 6000.369961154016, 1109.8834622364698, 1241.4413513918773, 1333.3799783100906, 1347.063790665478, 1379.3930670504947, 773.0144066315493, 780.7696729464822, 803.4857691077598, 846.3736834742369, 847.8982522097265, 3891.8248678809523, 3945.3292940199663, 4525.895458611762, 4539.75031373271, 4614.907701845944, 4172.7704976226, 4841.830535931225, 4998.550420378122, 5025.291400923704, 5039.79840806371, 4089.815958281825, 4420.58004489573, 4604.743675598999, 4612.677112602153, 4626.976865115653, 2205.933821985343, 2231.877246751412, 2384.3428816972387, 2457.680501534825, 2660.5124208864772, 4575.107853968444, 4584.724916505021, 4828.961773218704, 5130.510803047334, 5182.766775495112, 4303.978480107579, 4422.452141750426, 4473.724988378297, 4477.171723231213, 4811.439534744334, 5062.215356619729, 5683.914741278884, 6034.275886893601, 6130.75070212788, 6245.718942647501, 5042.747862606929, 5806.709664516673, 5928.525717942468, 6138.508919286357, 6142.0, 5650.85872853182, 6062.6665533394325, 6081.331053584191, 6110.480609148272, 6117.0, 5188.351456067991, 5427.430119837518, 6189.81430557084, 6230.345813689679, 6233.625982441068, 3969.2656858481273, 4026.651071890832, 4029.1941611677194, 4032.9395059074723, 4036.515618125726, 5551.500364967173, 5714.142928535732, 5964.270361595089, 6108.35862234459, 6153.72308246139, 5004.649674522749, 5714.571407144397, 5798.971005144947, 5929.703514824155, 5932.16949627044, 4754.049190162051, 4853.635977301646, 4983.476734942778, 5940.108983652487, 5943.197668314722, 5064.0756512525, 5360.383555891122, 5473.480019398151, 5786.826395208151, 5795.420457954171, 5128.692278463304, 5874.882502350015, 5885.911711324419, 5890.823275301748, 6077.878442431038, 5276.683398996072, 5511.917321240265, 5789.565782566408, 5828.679422631796, 5833.1250312451775], "value": 4075.937617234953, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.4225137507431, 699.3565919354197, 737.524296828546, 739.4047791527821, 779.8128449172196, 15085.92457037716, 15335.619794218517, 15630.3747850086, 15738.685226295467, 15777.526674199778, 14338.713225735479, 14510.27448627567, 14531.273436328194, 14541.854581454183, 14584.343704533296, 817.3869597801634, 826.6817275348974, 848.4569875279826, 856.2892798976866, 859.7119964811795, 686.9347411995852, 692.6640579319027, 836.3977935886182, 842.6839935024366, 851.0638297872322, 862.8490014247513, 904.683360823713, 908.4140729229642, 908.9818203635923, 909.5315912305168, 897.5198268926093, 901.0718959471754, 901.2879824938357, 901.995490022549, 902.6795487601905, 658.2857599504543, 669.2638098092109, 673.0745225315192, 673.4343151752552, 673.7709178879155, 14334.42662293507, 14577.0, 14771.630709232355, 14785.408583656641, 14985.02597331181, 14330.0, 14536.563903082819, 14554.927225363912, 14744.967852250347, 14892.62768430787, 11795.705107372298, 11843.881561184364, 11903.16677832552, 11944.761104777943, 12126.151169418144, 3767.5478942526797, 3775.263823554408, 3838.9808050959846, 3839.4816699745456, 3853.3064048471265, 107480.4625976865, 109880.0, 113989.0, 115569.4221528887, 117120.0, 11058.11535077192, 11204.271722338184, 11224.095765220736, 11255.831162532404, 11443.0273426758, 23030.963606637742, 23036.76963230346, 23037.88481057584, 23085.96113174911, 23121.768782311963, 7808.414368922354, 7917.485363451357, 7963.124056353912, 7991.44059915812, 8140.796480088045, 4317.546657600907, 4382.298832186835, 4386.8025938832825, 4473.083017981306, 4527.411436513232, 3450.879219227315, 3588.9102772430897, 3593.7304702147444, 3599.1901822090344, 3627.1657518770303, 546.4453579616692, 623.7473823101651, 631.5926227583183, 644.9613023218621, 652.3085529338916, 509.6519705379325, 567.3900556901348, 651.4234902111665, 661.8146918862726, 707.5507053021265, 8466.703665371806, 9263.90736092644, 9398.859017114748, 9579.185769209556, 9625.278104142217, 592.0704493944465, 629.9008230637567, 645.2998496631208, 645.8546826963895, 668.9364510371507, 3786.769299977518, 4721.15019296523, 4732.479427262998, 4768.451628062817, 4817.253325734447, 99735.50132249437, 147003.0, 150773.0, 166539.0, 169946.0, 91176.0, 91687.0, 125189.0, 146760.0, 150834.24582877016, 114657.4267128659, 117466.41266793782, 117605.41197294129, 118930.21604675936, 119728.4013579944, 9055.501947392957, 9070.954645226731, 9130.49782261982, 9138.588763505526, 9157.542122893696, 64949.67525162533, 65994.34005659881, 66603.33396665972, 67037.0, 67911.3208867905, 63905.0, 67656.98514522385, 81746.18253817386, 84696.57651711702, 96364.0, 13419.86580134186, 13863.168209907439, 14240.430382784987, 14259.42962281497, 14475.203863787592, 14618.195999219719, 14757.483488077865, 15476.535703928901, 15532.922335388703, 15597.844021559638, 13613.047086703835, 14014.509492167725, 14015.439382424589, 14284.928575357057, 14427.206503642401, 13745.24401157946, 13833.170009799444, 13965.720685586028, 14043.016988810685, 14462.639260000233, 13375.264360460269, 13439.529616463376, 14005.369758361103, 14107.576772696835, 14267.0, 11120.499577518869, 11324.660260192208, 11328.376939268419, 11475.540978361107, 11764.941155295723, 9742.659006934724, 10305.330153539995, 10672.946635266773, 11575.421228938689, 11713.590024349107], "value": 7839.9109735938655, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1603.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 119.0, 117.0, 117.0, 115.0, 117.0, 116.0, 126.0, 117.0, 116.0], "value": 117.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 118.0, 115.0, 156.0, 117.0, 147.0, 147.0, 118.0, 146.0, 115.0], "value": 133.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [270.0, 198.0, 187.0, 212.0, 209.0, 280.0, 278.0, 229.0, 197.0, 206.0], "value": 226.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 85.0, 64.0, 77.0, 85.0, 88.0, 94.0, 58.0], "value": 75.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 80.0, 66.0, 73.0, 71.0, 89.0, 69.0, 72.0, 87.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 87.0, 90.0, 86.0, 89.0, 85.0, 108.0, 82.0, 124.0, 115.0], "value": 97.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 166.0, 164.0, 172.0, 125.0, 100.0, 98.0, 102.0, 100.0, 120.0], "value": 126.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 100.0, 92.0, 116.0, 145.0, 89.0, 94.0, 92.0, 91.0], "value": 100.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 59.0, 67.0, 82.0, 88.0, 86.0, 49.0, 53.0, 54.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 76.0, 94.0, 71.0, 81.0, 111.0, 77.0, 63.0, 85.0, 72.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 100.0, 112.0, 87.0, 91.0, 91.0, 90.0, 87.0, 77.0, 106.0], "value": 92.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 153.0, 163.0, 172.0, 163.0, 148.0, 148.0, 180.0, 149.0], "value": 154.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 170.0, 174.0, 173.0, 178.0, 193.0, 249.0, 236.0, 171.0, 173.0], "value": 188.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 69.0, 69.0, 70.0, 68.0, 70.0, 72.0, 68.0, 67.0], "value": 69.5, "unit": "score"}]}]} 06:37:56 INFO - Return code: 0 06:37:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:37:56 ERROR - # TBPL SUCCESS # 06:37:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:37:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:37:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:37:56 INFO - 2016-10-05 06:37:56.442 screenresolution[1963:22561] starting screenresolution argv=screenresolution get 06:37:56 INFO - 2016-10-05 06:37:56.445 screenresolution[1963:22561] Display 0: 1600x1200x32@60 06:37:56 INFO - 2016-10-05 06:37:56.451 screenresolution[1964:22564] starting screenresolution argv=screenresolution list 06:37:56 INFO - Available Modes on Display 0 06:37:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:37:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:37:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:37:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:37:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:37:56 INFO - Intel Iris: 06:37:56 INFO - Chipset Model: Intel Iris 06:37:56 INFO - Type: GPU 06:37:56 INFO - Bus: Built-In 06:37:56 INFO - VRAM (Dynamic, Max): 1536 MB 06:37:56 INFO - Vendor: Intel (0x8086) 06:37:56 INFO - Device ID: 0x0a2e 06:37:56 INFO - Revision ID: 0x0009 06:37:56 INFO - Displays: 06:37:56 INFO - Display: 06:37:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:37:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:37:56 INFO - Display Serial Number: 1600x1200 60 06:37:56 INFO - Main Display: Yes 06:37:56 INFO - Mirror: Off 06:37:56 INFO - Online: Yes 06:37:56 INFO - Rotation: Supported 06:37:56 INFO - Return code: 0 06:37:56 INFO - Running post-action listener: _resource_record_post_action 06:37:56 INFO - [mozharness: 2016-10-05 13:37:56.689007Z] Finished run-tests step (success) 06:37:56 INFO - Running post-run listener: _resource_record_post_run 06:37:56 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1273856; Write bytes: 709421056; Read time: 14; Write time: 2279 06:37:56 INFO - TinderboxPrint: CPU usage
54.4% 06:37:56 INFO - TinderboxPrint: I/O read bytes / time
1,273,856 / 14 06:37:56 INFO - TinderboxPrint: I/O write bytes / time
709,421,056 / 2,279 06:37:56 INFO - TinderboxPrint: CPU idle
2,209.1 (45.6%) 06:37:56 INFO - TinderboxPrint: CPU system
983.1 (20.3%) 06:37:56 INFO - TinderboxPrint: CPU user
1,657.5 (34.2%) 06:37:56 INFO - TinderboxPrint: Swap in / out
408,371,200 / 0 06:37:56 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 391598592; Write bytes: 385595392; Read time: 29283; Write time: 655 06:37:56 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1273856; Write bytes: 298271744; Read time: 14; Write time: 1558 06:37:57 INFO - Running post-run listener: _upload_blobber_files 06:37:57 INFO - Blob upload gear active. 06:37:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:37:57 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:37:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:37:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:37:57 INFO - (blobuploader) - INFO - Open directory for files ... 06:37:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:37:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:37:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:37:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:37:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:37:58 INFO - (blobuploader) - INFO - Done attempting. 06:37:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:37:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:37:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:37:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:37:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:37:59 INFO - (blobuploader) - INFO - Done attempting. 06:37:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:37:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:37:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:38:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:38:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:38:01 INFO - (blobuploader) - INFO - Done attempting. 06:38:01 INFO - (blobuploader) - INFO - Iteration through files over. 06:38:01 INFO - Return code: 0 06:38:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:38:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:38:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb007ac98efd27c004a9dcec215dcc05bde39c45cb878ac3eec3864547fef77457af64ddcd3392f5e8f2523ee18c573c0f4d09669833352901e2e9f939c4de54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c3461ba384d7ca432b18e1837dbeaa1a804eb029417d15590233164877fea1c11f1932fcc380bece8ad13d32d7c49ca940121ad6faaa37858f35bea182e22a4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a31654a7478315e62730958f626eff070324b6f510eb7ba0a01b2a7eebdc005dc49dbeec420a493ed4d9d65c12333b685f8406c79230d6418dd9fcec821d5300"} 06:38:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:38:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:38:01 INFO - Contents: 06:38:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb007ac98efd27c004a9dcec215dcc05bde39c45cb878ac3eec3864547fef77457af64ddcd3392f5e8f2523ee18c573c0f4d09669833352901e2e9f939c4de54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c3461ba384d7ca432b18e1837dbeaa1a804eb029417d15590233164877fea1c11f1932fcc380bece8ad13d32d7c49ca940121ad6faaa37858f35bea182e22a4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a31654a7478315e62730958f626eff070324b6f510eb7ba0a01b2a7eebdc005dc49dbeec420a493ed4d9d65c12333b685f8406c79230d6418dd9fcec821d5300"} 06:38:01 INFO - Running post-run listener: copy_logs_to_upload_dir 06:38:01 INFO - Copying logs to upload dir... 06:38:01 INFO - mkdir: /builds/slave/test/build/upload/logs 06:38:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.105311 ========= master_lag: 3.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-05 06:38:04.992407) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 06:38:04.996428) ========= 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.KWpY7mXVf6/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.MYJ1fdb0wm/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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/cb007ac98efd27c004a9dcec215dcc05bde39c45cb878ac3eec3864547fef77457af64ddcd3392f5e8f2523ee18c573c0f4d09669833352901e2e9f939c4de54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c3461ba384d7ca432b18e1837dbeaa1a804eb029417d15590233164877fea1c11f1932fcc380bece8ad13d32d7c49ca940121ad6faaa37858f35bea182e22a4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a31654a7478315e62730958f626eff070324b6f510eb7ba0a01b2a7eebdc005dc49dbeec420a493ed4d9d65c12333b685f8406c79230d6418dd9fcec821d5300"} build_url:https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010958 build_url: 'https://queue.taskcluster.net/v1/task/NTQi_jf2SA6Ma_Xl0asZgw/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/cb007ac98efd27c004a9dcec215dcc05bde39c45cb878ac3eec3864547fef77457af64ddcd3392f5e8f2523ee18c573c0f4d09669833352901e2e9f939c4de54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c3461ba384d7ca432b18e1837dbeaa1a804eb029417d15590233164877fea1c11f1932fcc380bece8ad13d32d7c49ca940121ad6faaa37858f35bea182e22a4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a31654a7478315e62730958f626eff070324b6f510eb7ba0a01b2a7eebdc005dc49dbeec420a493ed4d9d65c12333b685f8406c79230d6418dd9fcec821d5300"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 06:38:05.033992) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 06:38:05.036394) ========= 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.KWpY7mXVf6/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.MYJ1fdb0wm/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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.005537 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 06:38:05.084805) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 06:38:05.085102) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 06:38:05.088426) ========= ========= Total master_lag: 5.42 =========