builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0276 starttime: 1478909700.64 results: success (0) buildid: 20161111135755 builduid: 7b55138c4f7c4a078bc61fcedfa94433 revision: fc104971a4db41e38808e6412bc32e1900172f14 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-11 16:15:00.637541) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-11 16:15:00.637995) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-11 16:15:00.638298) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-11 16:15:00.678205) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 16:15:00.678478) ========= 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.4jOdIncKwl/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.5XkrtxD1Qb/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-11 16:15:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 145M=0s 2016-11-11 16:15:01 (145 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.638209 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 16:15:01.352851) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 16:15:01.353146) ========= 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.4jOdIncKwl/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.5XkrtxD1Qb/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.019888 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 16:15:01.406213) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-11 16:15:01.406549) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fc104971a4db41e38808e6412bc32e1900172f14 --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 fc104971a4db41e38808e6412bc32e1900172f14 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4jOdIncKwl/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.5XkrtxD1Qb/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-11 16:15:01,491 truncating revision to first 12 chars 2016-11-11 16:15:01,491 Setting DEBUG logging. 2016-11-11 16:15:01,491 attempt 1/10 2016-11-11 16:15:01,491 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fc104971a4db?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-11 16:15:02,566 unpacking tar archive at: mozilla-central-fc104971a4db/testing/mozharness/ program finished with exit code 0 elapsedTime=1.343079 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-11 16:15:02.771747) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-11 16:15:02.772079) ========= script_repo_revision: fc104971a4db41e38808e6412bc32e1900172f14 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-11 16:15:02.775713) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-11 16:15:02.776028) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-11 16:15:02.795248) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-11 16:15:02.795659) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4jOdIncKwl/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.5XkrtxD1Qb/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 16:15:02 INFO - MultiFileLogger online at 20161111 16:15:02 in /builds/slave/test 16:15:02 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 16:15:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:15:02 INFO - {'append_to_log': False, 16:15:02 INFO - 'base_work_dir': '/builds/slave/test', 16:15:02 INFO - 'blob_upload_branch': 'Firefox', 16:15:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:15:02 INFO - 'branch': 'Firefox', 16:15:02 INFO - 'buildbot_json_path': 'buildprops.json', 16:15:02 INFO - 'config_files': ('talos/mac_config.py',), 16:15:02 INFO - 'default_actions': ('clobber', 16:15:02 INFO - 'read-buildbot-config', 16:15:02 INFO - 'download-and-extract', 16:15:02 INFO - 'populate-webroot', 16:15:02 INFO - 'create-virtualenv', 16:15:02 INFO - 'install', 16:15:02 INFO - 'run-tests'), 16:15:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:15:02 INFO - 'download_minidump_stackwalk': True, 16:15:02 INFO - 'download_symbols': 'ondemand', 16:15:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:15:02 INFO - 'tooltool.py': '/tools/tooltool.py', 16:15:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:15:02 INFO - '/tools/misc-python/virtualenv.py')}, 16:15:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:15:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:15:02 INFO - 'installer_path': 'installer.exe', 16:15:02 INFO - 'log_level': 'info', 16:15:02 INFO - 'log_name': 'talos', 16:15:02 INFO - 'log_to_console': True, 16:15:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:15:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:15:02 INFO - 'opt_config_files': (), 16:15:02 INFO - 'pip_index': False, 16:15:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:15:02 INFO - 'cmd': ('bash', 16:15:02 INFO - '-c', 16:15:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:15:02 INFO - 'enabled': True, 16:15:02 INFO - 'halt_on_failure': False, 16:15:02 INFO - 'name': 'check_screen_resolution'},), 16:15:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:15:02 INFO - 'cmd': ('bash', 16:15:02 INFO - '-c', 16:15:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:15:02 INFO - 'enabled': True, 16:15:02 INFO - 'halt_on_failure': False, 16:15:02 INFO - 'name': 'check_screen_resolution'},), 16:15:02 INFO - 'run_cmd_checks_enabled': True, 16:15:02 INFO - 'sps_profile': False, 16:15:02 INFO - 'sps_profile_interval': 0, 16:15:02 INFO - 'suite': 'dromaeojs-e10s', 16:15:02 INFO - 'system_bits': '32', 16:15:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:15:02 INFO - 'title': 't-yosemite-r7-0276', 16:15:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:15:02 INFO - 'use_talos_json': True, 16:15:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:15:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:15:02 INFO - 'work_dir': 'build'} 16:15:03 INFO - [mozharness: 2016-11-12 00:15:02.999971Z] Running clobber step. 16:15:03 INFO - Running pre-action listener: _resource_record_pre_action 16:15:03 INFO - Running main action method: clobber 16:15:03 INFO - rmtree: /builds/slave/test/build 16:15:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:15:05 INFO - Running post-action listener: _resource_record_post_action 16:15:05 INFO - [mozharness: 2016-11-12 00:15:05.039127Z] Finished clobber step (success) 16:15:05 INFO - [mozharness: 2016-11-12 00:15:05.039263Z] Running read-buildbot-config step. 16:15:05 INFO - Running pre-action listener: _resource_record_pre_action 16:15:05 INFO - Running main action method: read_buildbot_config 16:15:05 INFO - Using buildbot properties: 16:15:05 INFO - { 16:15:05 INFO - "project": "", 16:15:05 INFO - "product": "firefox", 16:15:05 INFO - "script_repo_revision": "production", 16:15:05 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:15:05 INFO - "repository": "", 16:15:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:15:05 INFO - "buildid": "20161111135755", 16:15:05 INFO - "pgo_build": "False", 16:15:05 INFO - "basedir": "/builds/slave/test", 16:15:05 INFO - "buildnumber": 166, 16:15:05 INFO - "slavename": "t-yosemite-r7-0276", 16:15:05 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 16:15:05 INFO - "platform": "yosemite_r7", 16:15:05 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:15:05 INFO - "branch": "mozilla-central", 16:15:05 INFO - "repo_path": "mozilla-central", 16:15:05 INFO - "revision": "fc104971a4db41e38808e6412bc32e1900172f14", 16:15:05 INFO - "stage_platform": "macosx64", 16:15:05 INFO - "builduid": "7b55138c4f7c4a078bc61fcedfa94433", 16:15:05 INFO - "slavebuilddir": "test" 16:15:05 INFO - } 16:15:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 16:15:05 INFO - Running post-action listener: _resource_record_post_action 16:15:05 INFO - [mozharness: 2016-11-12 00:15:05.042622Z] Finished read-buildbot-config step (success) 16:15:05 INFO - [mozharness: 2016-11-12 00:15:05.042712Z] Running download-and-extract step. 16:15:05 INFO - Running pre-action listener: _resource_record_pre_action 16:15:05 INFO - Running main action method: download_and_extract 16:15:05 INFO - mkdir: /builds/slave/test/build/tests 16:15:05 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')]} 16:15:05 INFO - trying https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 16:15:05 INFO - Downloading https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/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 16:15:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/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 16:15:06 INFO - Downloaded 1199 bytes. 16:15:06 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:15:06 INFO - Using the following test package requirements: 16:15:06 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:15:06 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:15:06 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 16:15:06 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:15:06 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 16:15:06 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:15:06 INFO - u'jsshell-mac.zip'], 16:15:06 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:15:06 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 16:15:06 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:15:06 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:15:06 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 16:15:06 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:15:06 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 16:15:06 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:15:06 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 16:15:06 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:15:06 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 16:15:06 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:15:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:15:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:15:06 INFO - Fetch https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:15:06 INFO - Http code: 200 16:15:06 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 16:15:06 INFO - x-amz-cf-id: PP4_jxLr48QPuU-oPLeqsOt2198wPRdN3oB1YNkn36pE8XWsTHUJlQ== 16:15:06 INFO - x-amz-version-id: WBKRjzmFF0J2swVdMWT.vPz2a8PJWzsw 16:15:07 INFO - Expected file size: 16333925 16:15:07 INFO - Obtained file size: 16333925 16:15:13 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 16:15:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:15:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:15:13 INFO - Fetch https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:15:14 INFO - Http code: 200 16:15:14 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 16:15:14 INFO - x-amz-cf-id: Bbw8YFH5SYLjflzbrewGUuWSJKtmSyMA3lVAxnMGwtN4nV3I8g81OA== 16:15:14 INFO - x-amz-version-id: WBKRjzmFF0J2swVdMWT.vPz2a8PJWzsw 16:15:14 INFO - Expected file size: 16333925 16:15:14 INFO - Obtained file size: 16333925 16:15:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 16:15:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:15:21 INFO - Fetch https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 16:15:21 INFO - Http code: 200 16:15:21 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 16:15:21 INFO - x-amz-cf-id: -jTyknD0r0fjjWjExRdkNnJcvxixfLhfLCFBWyAAWQSIwl_fskM1sA== 16:15:21 INFO - x-amz-version-id: YyMO3.vGdtQ7hV4.gZF5uybnjzLaZQa8 16:15:22 INFO - Expected file size: 14409038 16:15:22 INFO - Obtained file size: 14409038 16:15:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:22 INFO - trying https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:15:22 INFO - Downloading https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 16:15:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/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 16:15:25 INFO - Downloaded 111074596 bytes. 16:15:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:15:25 INFO - mkdir: /builds/slave/test/properties 16:15:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:15:25 INFO - Writing to file /builds/slave/test/properties/build_url 16:15:25 INFO - Contents: 16:15:25 INFO - build_url:https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:15:26 INFO - Running post-action listener: _resource_record_post_action 16:15:26 INFO - Running post-action listener: set_extra_try_arguments 16:15:26 INFO - [mozharness: 2016-11-12 00:15:26.233490Z] Finished download-and-extract step (success) 16:15:26 INFO - [mozharness: 2016-11-12 00:15:26.233614Z] Running populate-webroot step. 16:15:26 INFO - Running pre-action listener: _resource_record_pre_action 16:15:26 INFO - Running main action method: populate_webroot 16:15:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:15:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:15:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:15:26 INFO - '--tppagecycles', 16:15:26 INFO - '7'], 16:15:26 INFO - 'tests': ['tsvgm']}}, 16:15:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:15:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:15:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:15:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:15:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:15:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:15:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:15:26 INFO - 'talos_options': ['--disable-e10s'], 16:15:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:15:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:15:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:15:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:15:26 INFO - 'talos_options': ['--disable-e10s'], 16:15:26 INFO - 'tests': ['damp', 'tps']}, 16:15:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:15:26 INFO - 'tests': ['damp', 'tps']}, 16:15:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:15:26 INFO - 'tests': ['dromaeo_dom']}, 16:15:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:15:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:15:26 INFO - 'tests': ['basic_compositor_video']}, 16:15:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:15:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:15:26 INFO - 'tests': ['a11yr', 16:15:26 INFO - 'ts_paint', 16:15:26 INFO - 'tpaint', 16:15:26 INFO - 'sessionrestore', 16:15:26 INFO - 'sessionrestore_no_auto_restore', 16:15:26 INFO - 'tabpaint']}, 16:15:26 INFO - 'other-e10s': {'tests': ['a11yr', 16:15:26 INFO - 'ts_paint', 16:15:26 INFO - 'tpaint', 16:15:26 INFO - 'sessionrestore', 16:15:26 INFO - 'sessionrestore_no_auto_restore', 16:15:26 INFO - 'tabpaint']}, 16:15:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:15:26 INFO - 'ts_paint', 16:15:26 INFO - 'tpaint', 16:15:26 INFO - 'sessionrestore', 16:15:26 INFO - 'sessionrestore_no_auto_restore', 16:15:26 INFO - 'tabpaint']}, 16:15:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:15:26 INFO - 'ts_paint', 16:15:26 INFO - 'tpaint', 16:15:26 INFO - 'sessionrestore', 16:15:26 INFO - 'sessionrestore_no_auto_restore', 16:15:26 INFO - 'tabpaint']}, 16:15:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:15:26 INFO - 'tests': ['a11yr', 16:15:26 INFO - 'ts_paint', 16:15:26 INFO - 'tpaint', 16:15:26 INFO - 'sessionrestore', 16:15:26 INFO - 'sessionrestore_no_auto_restore', 16:15:26 INFO - 'tabpaint']}, 16:15:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:15:26 INFO - 'tests': ['a11yr', 16:15:26 INFO - 'ts_paint', 16:15:26 INFO - 'tpaint', 16:15:26 INFO - 'sessionrestore', 16:15:26 INFO - 'sessionrestore_no_auto_restore', 16:15:26 INFO - 'tabpaint']}, 16:15:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:15:26 INFO - 'tests': ['tsvgx', 16:15:26 INFO - 'tsvgr_opacity', 16:15:26 INFO - 'tart', 16:15:26 INFO - 'tscrollx', 16:15:26 INFO - 'cart']}, 16:15:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:15:26 INFO - 'tsvgr_opacity', 16:15:26 INFO - 'tart', 16:15:26 INFO - 'tscrollx', 16:15:26 INFO - 'cart']}, 16:15:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:15:26 INFO - 'talos_options': ['--disable-e10s'], 16:15:26 INFO - 'tests': ['tp5o']}, 16:15:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:15:26 INFO - 'tests': ['tp5o']}, 16:15:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:15:26 INFO - 'talos_options': ['--disable-e10s', 16:15:26 INFO - '--xperf_path', 16:15:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:15:26 INFO - 'tests': ['tp5n']}, 16:15:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:15:26 INFO - 'talos_options': ['--xperf_path', 16:15:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:15:26 INFO - 'tests': ['tp5n']}}, 16:15:26 INFO - 'talos.zip': {'path': '', 16:15:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:15:26 INFO - Running post-action listener: _resource_record_post_action 16:15:26 INFO - [mozharness: 2016-11-12 00:15:26.252669Z] Finished populate-webroot step (success) 16:15:26 INFO - [mozharness: 2016-11-12 00:15:26.252812Z] Running create-virtualenv step. 16:15:26 INFO - Running pre-action listener: _resource_record_pre_action 16:15:26 INFO - Running main action method: create_virtualenv 16:15:26 INFO - Creating virtualenv /builds/slave/test/build/venv 16:15:26 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 16:15:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:15:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:15:26 INFO - Using real prefix '/tools/python27' 16:15:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:15:27 INFO - Installing distribute.............................................................................................................................................................................................done. 16:15:30 INFO - Installing pip.................done. 16:15:30 INFO - Return code: 0 16:15:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:15:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:15:30 INFO - Reading from file tmpfile_stdout 16:15:30 INFO - Output received: 16:15:30 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) 16:15:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:15:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:30 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 0x10b469490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47bdcb300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27dd30>, '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 0x10b0a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b275b20>, '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.4jOdIncKwl/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.5XkrtxD1Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 16:15:30 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 16:15:30 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 16:15:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jOdIncKwl/Render', 16:15:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:30 INFO - 'HOME': '/Users/cltbld', 16:15:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:30 INFO - 'LOGNAME': 'cltbld', 16:15:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:30 INFO - 'MOZ_NO_REMOTE': '1', 16:15:30 INFO - 'NO_EM_RESTART': '1', 16:15:30 INFO - 'PAGER': '/bin/cat', 16:15:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:30 INFO - 'PWD': '/builds/slave/test', 16:15:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:30 INFO - 'SHELL': '/bin/bash', 16:15:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5XkrtxD1Qb/Listeners', 16:15:30 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 16:15:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:30 INFO - 'USER': 'cltbld', 16:15:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:30 INFO - 'XPC_FLAGS': '0x0', 16:15:30 INFO - 'XPC_SERVICE_NAME': '0', 16:15:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:30 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 16:15:30 INFO - Cleaning up... 16:15:30 INFO - Return code: 0 16:15:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:15:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:30 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 0x10b469490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47bdcb300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27dd30>, '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 0x10b0a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b275b20>, '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.4jOdIncKwl/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.5XkrtxD1Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 16:15:30 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 16:15:30 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 16:15:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jOdIncKwl/Render', 16:15:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:30 INFO - 'HOME': '/Users/cltbld', 16:15:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:30 INFO - 'LOGNAME': 'cltbld', 16:15:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:30 INFO - 'MOZ_NO_REMOTE': '1', 16:15:30 INFO - 'NO_EM_RESTART': '1', 16:15:30 INFO - 'PAGER': '/bin/cat', 16:15:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:30 INFO - 'PWD': '/builds/slave/test', 16:15:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:30 INFO - 'SHELL': '/bin/bash', 16:15:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5XkrtxD1Qb/Listeners', 16:15:30 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 16:15:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:30 INFO - 'USER': 'cltbld', 16:15:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:30 INFO - 'XPC_FLAGS': '0x0', 16:15:30 INFO - 'XPC_SERVICE_NAME': '0', 16:15:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:30 INFO - Downloading/unpacking psutil>=3.1.1 16:15: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 16:15: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 16:15: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 16:15: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 16:15: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 16:15: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 16:15:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:15:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:15:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:15:34 INFO - Installing collected packages: psutil 16:15:34 INFO - Running setup.py install for psutil 16:15:34 INFO - building 'psutil._psutil_osx' extension 16:15:34 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 16:15:35 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 16:15:35 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 16:15:35 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 16:15:35 INFO - building 'psutil._psutil_posix' extension 16:15:35 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 16:15:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:15:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:15:35 INFO - ^ 16:15:35 INFO - 1 warning generated. 16:15:35 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 16:15:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:15:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:15:35 INFO - Successfully installed psutil 16:15:35 INFO - Cleaning up... 16:15:35 INFO - Return code: 0 16:15:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:15:35 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')]} 16:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:35 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')]} 16:15:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:35 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 0x10b469490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47bdcb300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27dd30>, '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 0x10b0a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b275b20>, '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.4jOdIncKwl/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.5XkrtxD1Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 16:15:35 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 16:15:35 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 16:15:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jOdIncKwl/Render', 16:15:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:35 INFO - 'HOME': '/Users/cltbld', 16:15:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:35 INFO - 'LOGNAME': 'cltbld', 16:15:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:35 INFO - 'MOZ_NO_REMOTE': '1', 16:15:35 INFO - 'NO_EM_RESTART': '1', 16:15:35 INFO - 'PAGER': '/bin/cat', 16:15:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:35 INFO - 'PWD': '/builds/slave/test', 16:15:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:35 INFO - 'SHELL': '/bin/bash', 16:15:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5XkrtxD1Qb/Listeners', 16:15:35 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 16:15:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:35 INFO - 'USER': 'cltbld', 16:15:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:35 INFO - 'XPC_FLAGS': '0x0', 16:15:35 INFO - 'XPC_SERVICE_NAME': '0', 16:15:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:15:35 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 16:15:35 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 16:15:35 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 16:15:35 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 16:15:35 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 16:15:35 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 16:15:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:15:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:15:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:15:38 INFO - Installing collected packages: mozsystemmonitor 16:15:38 INFO - Running setup.py install for mozsystemmonitor 16:15:38 INFO - Successfully installed mozsystemmonitor 16:15:38 INFO - Cleaning up... 16:15:38 INFO - Return code: 0 16:15:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:15:38 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')]} 16:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:38 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')]} 16:15:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:38 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 0x10b469490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47bdcb300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27dd30>, '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 0x10b0a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b275b20>, '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.4jOdIncKwl/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.5XkrtxD1Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 16:15:38 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 16:15:38 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 16:15:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jOdIncKwl/Render', 16:15:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:38 INFO - 'HOME': '/Users/cltbld', 16:15:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:38 INFO - 'LOGNAME': 'cltbld', 16:15:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:38 INFO - 'MOZ_NO_REMOTE': '1', 16:15:38 INFO - 'NO_EM_RESTART': '1', 16:15:38 INFO - 'PAGER': '/bin/cat', 16:15:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:38 INFO - 'PWD': '/builds/slave/test', 16:15:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:38 INFO - 'SHELL': '/bin/bash', 16:15:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5XkrtxD1Qb/Listeners', 16:15:38 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 16:15:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:38 INFO - 'USER': 'cltbld', 16:15:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:38 INFO - 'XPC_FLAGS': '0x0', 16:15:38 INFO - 'XPC_SERVICE_NAME': '0', 16:15:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:38 INFO - Downloading/unpacking jsonschema==2.5.1 16:15:38 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 16:15:38 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 16:15:38 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 16:15:38 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 16:15:38 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 16:15:38 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 16:15:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:15:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:15:41 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 16:15:41 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 16:15:41 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 16:15:41 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 16:15:41 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 16:15:41 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 16:15:41 INFO - Downloading functools32-3.2.3-2.tar.gz 16:15:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:15:41 INFO - warning: no files found matching '*.txt' 16:15:41 INFO - no previously-included directories found matching 'build' 16:15:41 INFO - no previously-included directories found matching 'dist' 16:15:41 INFO - no previously-included directories found matching '.git*' 16:15:41 INFO - Installing collected packages: jsonschema, functools32 16:15:41 INFO - Running setup.py install for functools32 16:15:42 INFO - warning: no files found matching '*.txt' 16:15:42 INFO - no previously-included directories found matching 'build' 16:15:42 INFO - no previously-included directories found matching 'dist' 16:15:42 INFO - no previously-included directories found matching '.git*' 16:15:42 INFO - Successfully installed jsonschema functools32 16:15:42 INFO - Cleaning up... 16:15:42 INFO - Return code: 0 16:15:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:15: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')]} 16:15:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15: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')]} 16:15:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:42 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 0x10b469490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47bdcb300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27dd30>, '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 0x10b0a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b275b20>, '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.4jOdIncKwl/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.5XkrtxD1Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 16:15:42 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 16:15:42 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 16:15:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jOdIncKwl/Render', 16:15:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:42 INFO - 'HOME': '/Users/cltbld', 16:15:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:42 INFO - 'LOGNAME': 'cltbld', 16:15:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:42 INFO - 'MOZ_NO_REMOTE': '1', 16:15:42 INFO - 'NO_EM_RESTART': '1', 16:15:42 INFO - 'PAGER': '/bin/cat', 16:15:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:42 INFO - 'PWD': '/builds/slave/test', 16:15:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:42 INFO - 'SHELL': '/bin/bash', 16:15:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5XkrtxD1Qb/Listeners', 16:15:42 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 16:15:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:42 INFO - 'USER': 'cltbld', 16:15:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:42 INFO - 'XPC_FLAGS': '0x0', 16:15:42 INFO - 'XPC_SERVICE_NAME': '0', 16:15:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:15:42 INFO - Cleaning up... 16:15:42 INFO - Return code: 0 16:15:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:15: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')]} 16:15:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15: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')]} 16:15:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:42 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 0x10b469490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47bdcb300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27dd30>, '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 0x10b0a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b275b20>, '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.4jOdIncKwl/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.5XkrtxD1Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 16:15:42 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 16:15:42 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 16:15:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jOdIncKwl/Render', 16:15:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:42 INFO - 'HOME': '/Users/cltbld', 16:15:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:42 INFO - 'LOGNAME': 'cltbld', 16:15:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:42 INFO - 'MOZ_NO_REMOTE': '1', 16:15:42 INFO - 'NO_EM_RESTART': '1', 16:15:42 INFO - 'PAGER': '/bin/cat', 16:15:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:42 INFO - 'PWD': '/builds/slave/test', 16:15:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:42 INFO - 'SHELL': '/bin/bash', 16:15:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5XkrtxD1Qb/Listeners', 16:15:42 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 16:15:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:42 INFO - 'USER': 'cltbld', 16:15:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:42 INFO - 'XPC_FLAGS': '0x0', 16:15:42 INFO - 'XPC_SERVICE_NAME': '0', 16:15:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:42 INFO - Downloading/unpacking blobuploader==1.2.4 16:15:42 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 16:15:42 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 16:15:42 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 16:15:42 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 16:15:42 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 16:15:42 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 16:15:45 INFO - Downloading blobuploader-1.2.4.tar.gz 16:15:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:15:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:15:45 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 16:15:45 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 16:15:45 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 16:15:45 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 16:15:45 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 16:15:45 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 16:15:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:15:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:15:45 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 16:15:45 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 16:15:45 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 16:15:45 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 16:15:45 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 16:15:45 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 16:15:46 INFO - Downloading docopt-0.6.1.tar.gz 16:15:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:15:46 INFO - Installing collected packages: blobuploader, requests, docopt 16:15:46 INFO - Running setup.py install for blobuploader 16:15:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:15:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:15:46 INFO - Running setup.py install for requests 16:15:47 INFO - Running setup.py install for docopt 16:15:47 INFO - Successfully installed blobuploader requests docopt 16:15:47 INFO - Cleaning up... 16:15:47 INFO - Return code: 0 16:15:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:47 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 0x10b469490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47bdcb300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27dd30>, '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 0x10b0a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b275b20>, '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.4jOdIncKwl/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.5XkrtxD1Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 16:15:47 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 16:15:47 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 16:15:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jOdIncKwl/Render', 16:15:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:47 INFO - 'HOME': '/Users/cltbld', 16:15:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:47 INFO - 'LOGNAME': 'cltbld', 16:15:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:47 INFO - 'MOZ_NO_REMOTE': '1', 16:15:47 INFO - 'NO_EM_RESTART': '1', 16:15:47 INFO - 'PAGER': '/bin/cat', 16:15:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:47 INFO - 'PWD': '/builds/slave/test', 16:15:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:47 INFO - 'SHELL': '/bin/bash', 16:15:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5XkrtxD1Qb/Listeners', 16:15:47 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 16:15:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:47 INFO - 'USER': 'cltbld', 16:15:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:47 INFO - 'XPC_FLAGS': '0x0', 16:15:47 INFO - 'XPC_SERVICE_NAME': '0', 16:15:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:15:47 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-f460s3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:15:47 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-yFGJux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:15:47 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-yo1LkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:15:47 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Kl9JqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:15:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-ZEe7x8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:15:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-ntEKJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:15:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-UgWeq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:15:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-71lVZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:15:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-LrjiEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:15:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Zn9PYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:15:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-s_8lOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:15:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-XhcMh5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:15:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-2B_4oO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:15:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-iA9x1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-CNze5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:15:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-FzeMkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:15:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-87zRCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:15:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:15:49 INFO - Running setup.py install for manifestparser 16:15:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:15:49 INFO - Running setup.py install for mozcrash 16:15:49 INFO - Running setup.py install for mozdebug 16:15:49 INFO - Running setup.py install for mozdevice 16:15:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Running setup.py install for mozfile 16:15:50 INFO - Running setup.py install for mozhttpd 16:15:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Running setup.py install for mozinfo 16:15:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Running setup.py install for mozInstall 16:15:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Running setup.py install for mozleak 16:15:50 INFO - Running setup.py install for mozlog 16:15:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Running setup.py install for moznetwork 16:15:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:15:51 INFO - Running setup.py install for mozprocess 16:15:51 INFO - Running setup.py install for mozprofile 16:15:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:15:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:15:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:15:51 INFO - Running setup.py install for mozrunner 16:15:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:15:51 INFO - Running setup.py install for mozscreenshot 16:15:51 INFO - Running setup.py install for moztest 16:15:51 INFO - Running setup.py install for mozversion 16:15:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:15:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:15:52 INFO - Cleaning up... 16:15:52 INFO - Return code: 0 16:15:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:52 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')]} 16:15:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:52 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')]} 16:15:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:52 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 0x10b469490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47bdcb300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27dd30>, '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 0x10b0a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b275b20>, '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.4jOdIncKwl/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.5XkrtxD1Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 16:15:52 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 16:15:52 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 16:15:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jOdIncKwl/Render', 16:15:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:52 INFO - 'HOME': '/Users/cltbld', 16:15:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:52 INFO - 'LOGNAME': 'cltbld', 16:15:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:52 INFO - 'MOZ_NO_REMOTE': '1', 16:15:52 INFO - 'NO_EM_RESTART': '1', 16:15:52 INFO - 'PAGER': '/bin/cat', 16:15:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:52 INFO - 'PWD': '/builds/slave/test', 16:15:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:52 INFO - 'SHELL': '/bin/bash', 16:15:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5XkrtxD1Qb/Listeners', 16:15:52 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 16:15:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:52 INFO - 'USER': 'cltbld', 16:15:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:52 INFO - 'XPC_FLAGS': '0x0', 16:15:52 INFO - 'XPC_SERVICE_NAME': '0', 16:15:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:15:52 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-p_ghnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:15:52 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-9WV6kD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:15:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:15:52 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-x15s7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:15:52 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-03JXJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:15:52 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-OjktFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:15:52 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-ERJwPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:15:52 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-91wcXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:15:53 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)) 16:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:15:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-0DTWAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:15:53 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)) 16:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:15:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Zidjra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:15:53 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)) 16:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:15:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-rsIP25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:15:53 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)) 16:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:15:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-BHh7xM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:15:53 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)) 16:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:15:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-o4qfNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:15:53 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)) 16:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:15:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-MDx8B4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:15:53 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)) 16:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:15:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-f56kQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:15:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-wTKSwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:53 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)) 16:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:15:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-2Ke18C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:15:54 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)) 16:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:15:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-DZKBJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:15:54 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)) 16:15:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:15:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:15:54 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)) 16:15:54 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)) 16:15:54 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)) 16:15:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:56 INFO - Downloading blessings-1.6.tar.gz 16:15:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:15:56 INFO - Installing collected packages: blessings 16:15:56 INFO - Running setup.py install for blessings 16:15:56 INFO - Successfully installed blessings 16:15:56 INFO - Cleaning up... 16:15:56 INFO - Return code: 0 16:15:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:15:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:15:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:15:57 INFO - Reading from file tmpfile_stdout 16:15:57 INFO - Current package versions: 16:15:57 INFO - blessings == 1.6 16:15:57 INFO - blobuploader == 1.2.4 16:15:57 INFO - docopt == 0.6.1 16:15:57 INFO - functools32 == 3.2.3-2 16:15:57 INFO - jsonschema == 2.5.1 16:15:57 INFO - manifestparser == 1.1 16:15:57 INFO - mozInstall == 1.12 16:15:57 INFO - mozcrash == 1.0 16:15:57 INFO - mozdebug == 0.1 16:15:57 INFO - mozdevice == 0.48 16:15:57 INFO - mozfile == 1.2 16:15:57 INFO - mozhttpd == 0.7 16:15:57 INFO - mozinfo == 0.9 16:15:57 INFO - mozleak == 0.1 16:15:57 INFO - mozlog == 3.4 16:15:57 INFO - moznetwork == 0.27 16:15:57 INFO - mozprocess == 0.23 16:15:57 INFO - mozprofile == 0.28 16:15:57 INFO - mozrunner == 6.13 16:15:57 INFO - mozscreenshot == 0.1 16:15:57 INFO - mozsystemmonitor == 0.3 16:15:57 INFO - moztest == 0.7 16:15:57 INFO - mozversion == 1.4 16:15:57 INFO - psutil == 3.1.1 16:15:57 INFO - requests == 1.2.3 16:15:57 INFO - wsgiref == 0.1.2 16:15:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:57 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 0x10b469490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47bdcb300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27dd30>, '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 0x10b0a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b275b20>, '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.4jOdIncKwl/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.5XkrtxD1Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 16:15:57 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 16:15:57 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 16:15:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jOdIncKwl/Render', 16:15:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:57 INFO - 'HOME': '/Users/cltbld', 16:15:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:57 INFO - 'LOGNAME': 'cltbld', 16:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:57 INFO - 'MOZ_NO_REMOTE': '1', 16:15:57 INFO - 'NO_EM_RESTART': '1', 16:15:57 INFO - 'PAGER': '/bin/cat', 16:15:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:57 INFO - 'PWD': '/builds/slave/test', 16:15:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:57 INFO - 'SHELL': '/bin/bash', 16:15:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5XkrtxD1Qb/Listeners', 16:15:57 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 16:15:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:57 INFO - 'USER': 'cltbld', 16:15:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:57 INFO - 'XPC_FLAGS': '0x0', 16:15:57 INFO - 'XPC_SERVICE_NAME': '0', 16:15:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:57 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)) 16:15:57 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)) 16:15:57 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)) 16:15:57 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)) 16:15:57 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)) 16:15:57 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)) 16:15:57 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)) 16:15:57 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)) 16:15:57 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)) 16:15:57 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)) 16:15:57 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)) 16:15:57 INFO - Cleaning up... 16:15:57 INFO - Return code: 0 16:15:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b469490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47bdcb300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27dd30>, '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 0x10b0a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b275b20>, '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.4jOdIncKwl/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.5XkrtxD1Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 16:15:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:15:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jOdIncKwl/Render', 16:15:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:57 INFO - 'HOME': '/Users/cltbld', 16:15:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:57 INFO - 'LOGNAME': 'cltbld', 16:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:57 INFO - 'MOZ_NO_REMOTE': '1', 16:15:57 INFO - 'NO_EM_RESTART': '1', 16:15:57 INFO - 'PAGER': '/bin/cat', 16:15:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:57 INFO - 'PWD': '/builds/slave/test', 16:15:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:57 INFO - 'SHELL': '/bin/bash', 16:15:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5XkrtxD1Qb/Listeners', 16:15:57 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 16:15:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:57 INFO - 'USER': 'cltbld', 16:15:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:57 INFO - 'XPC_FLAGS': '0x0', 16:15:57 INFO - 'XPC_SERVICE_NAME': '0', 16:15:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:15:57 INFO - Cleaning up... 16:15:57 INFO - Return code: 0 16:15:57 INFO - Running post-action listener: _resource_record_post_action 16:15:57 INFO - Running post-action listener: _start_resource_monitoring 16:15:57 INFO - Starting resource monitoring. 16:15:57 INFO - [mozharness: 2016-11-12 00:15:57.834670Z] Finished create-virtualenv step (success) 16:15:57 INFO - [mozharness: 2016-11-12 00:15:57.835672Z] Running install step. 16:15:57 INFO - Running pre-action listener: _resource_record_pre_action 16:15:57 INFO - Running main action method: install 16:15:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:15:58 INFO - Reading from file tmpfile_stdout 16:15:58 INFO - Detecting whether we're running mozinstall >=1.0... 16:15:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:15:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:15:58 INFO - Reading from file tmpfile_stdout 16:15:58 INFO - Output received: 16:15:58 INFO - Usage: mozinstall [options] installer 16:15:58 INFO - Options: 16:15:58 INFO - -h, --help show this help message and exit 16:15:58 INFO - -d DEST, --destination=DEST 16:15:58 INFO - Directory to install application into. [default: 16:15:58 INFO - "/builds/slave/test"] 16:15:58 INFO - --app=APP Application being installed. [default: firefox] 16:15:58 INFO - mkdir: /builds/slave/test/build/application 16:15:58 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'] 16:15:58 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 16:16:32 INFO - Reading from file tmpfile_stdout 16:16:32 INFO - Output received: 16:16:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:16:32 INFO - Running post-action listener: _resource_record_post_action 16:16:32 INFO - [mozharness: 2016-11-12 00:16:32.211242Z] Finished install step (success) 16:16:32 INFO - [mozharness: 2016-11-12 00:16:32.211385Z] Running run-tests step. 16:16:32 INFO - Running pre-action listener: _resource_record_pre_action 16:16:32 INFO - Running main action method: run_tests 16:16:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:16:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:16:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:16:32 INFO - 2016-11-11 16:16:32.226 screenresolution[2214:26284] starting screenresolution argv=screenresolution get 16:16:32 INFO - 2016-11-11 16:16:32.228 screenresolution[2214:26284] Display 0: 1600x1200x32@60 16:16:32 INFO - 2016-11-11 16:16:32.234 screenresolution[2215:26286] starting screenresolution argv=screenresolution list 16:16:32 INFO - Available Modes on Display 0 16:16:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:16:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:16:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:16:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:16:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:16:32 INFO - Intel Iris: 16:16:32 INFO - Chipset Model: Intel Iris 16:16:32 INFO - Type: GPU 16:16:32 INFO - Bus: Built-In 16:16:32 INFO - VRAM (Dynamic, Max): 1536 MB 16:16:32 INFO - Vendor: Intel (0x8086) 16:16:32 INFO - Device ID: 0x0a2e 16:16:32 INFO - Revision ID: 0x0009 16:16:32 INFO - Displays: 16:16:32 INFO - Display: 16:16:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:16:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:16:32 INFO - Display Serial Number: 1600x1200 60 16:16:32 INFO - Main Display: Yes 16:16:32 INFO - Mirror: Off 16:16:32 INFO - Online: Yes 16:16:32 INFO - Rotation: Supported 16:16:32 INFO - Return code: 0 16:16:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:16:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:16:32 INFO - Python 2.7.3 16:16:32 INFO - Return code: 0 16:16:32 INFO - grabbing minidump binary from tooltool 16:16: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')]} 16:16:32 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 0x10b27dd30>, '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 0x10b0a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b275b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:16:32 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 16:16:32 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 16:16:32 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 16:16:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:16:32 INFO - Return code: 0 16:16:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:16:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:16:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:16:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:16:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:16:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--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 16:16:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0276 --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 16:16:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jOdIncKwl/Render', 16:16:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:32 INFO - 'HOME': '/Users/cltbld', 16:16:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:32 INFO - 'LOGNAME': 'cltbld', 16:16:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:16:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:32 INFO - 'MOZ_NO_REMOTE': '1', 16:16:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:16:32 INFO - 'NO_EM_RESTART': '1', 16:16:32 INFO - 'PAGER': '/bin/cat', 16:16:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:32 INFO - 'PWD': '/builds/slave/test', 16:16:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:16:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:32 INFO - 'SHELL': '/bin/bash', 16:16:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5XkrtxD1Qb/Listeners', 16:16:32 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 16:16:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:32 INFO - 'USER': 'cltbld', 16:16:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:32 INFO - 'XPC_FLAGS': '0x0', 16:16:32 INFO - 'XPC_SERVICE_NAME': '0', 16:16:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--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 16:16:32 INFO - mozversion application_buildid: 20161111135755 16:16:32 INFO - mozversion application_changeset: fc104971a4db41e38808e6412bc32e1900172f14 16:16:32 INFO - mozversion application_display_name: Nightly 16:16:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:16:32 INFO - mozversion application_name: Firefox 16:16:32 INFO - mozversion application_remotingname: firefox 16:16:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:16:32 INFO - mozversion application_vendor: Mozilla 16:16:32 INFO - mozversion application_version: 52.0a1 16:16:32 INFO - mozversion platform_buildid: 20161111135755 16:16:32 INFO - mozversion platform_changeset: fc104971a4db41e38808e6412bc32e1900172f14 16:16:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:16:32 INFO - mozversion platform_version: 52.0a1 16:16:32 INFO - using testdate: 1478909792 16:16:32 INFO - actual date: 1478909792 16:16:32 INFO - starting webserver on 'localhost:60827' 16:16:32 INFO - SUITE-START | Running 2 tests 16:16:32 INFO - TEST-START | dromaeo_css 16:16:32 INFO - operating with platform_type : mac_ 16:16:32 INFO - Initialising browser for dromaeo_css test... 16:16:32 INFO - TEST-INFO | started process 2225 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpLXclMA/profile http://localhost:60827/getInfo.html) 16:16:35 INFO - PROCESS | 2225 | 2016-11-11 16:16:35.627 plugin-container[2227:26437] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 16:16:35 INFO - PROCESS | 2225 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:16:35 INFO - PROCESS | 2225 | 2016-11-11 16:16:35.631 plugin-container[2227:26437] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 16:16:35 INFO - PROCESS | 2225 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:16:35 INFO - PROCESS | 2225 | 2016-11-11 16:16:35.631 plugin-container[2227:26437] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:16:35 INFO - PROCESS | 2225 | __metrics Screen width/height:1600/1200 16:16:35 INFO - PROCESS | 2225 | colorDepth:24 16:16:35 INFO - PROCESS | 2225 | Browser inner width/height: 1024/689 16:16:35 INFO - PROCESS | 2225 | __metrics 16:16:35 ERROR - PROCESS | 2225 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 16:16:36 INFO - TEST-INFO | 2225: exit 0 16:16:36 INFO - Browser initialized. 16:16:36 INFO - Running cycle 1/1 for dromaeo_css test... 16:16:36 INFO - TEST-INFO | started process 2228 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpLXclMA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:16:39 INFO - PROCESS | 2228 | 2016-11-11 16:16:39.133 plugin-container[2229:26560] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x872b, name = 'com.apple.tsm.portname' 16:16:39 INFO - PROCESS | 2228 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:16:39 INFO - PROCESS | 2228 | 2016-11-11 16:16:39.134 plugin-container[2229:26560] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 16:16:39 INFO - PROCESS | 2228 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:16:39 INFO - PROCESS | 2228 | 2016-11-11 16:16:39.134 plugin-container[2229:26560] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:16:48 INFO - PROCESS | 2228 | RSS: Main: 237469696 16:16:48 INFO - PROCESS | 2228 | 16:17:38 INFO - PROCESS | 2228 | 1478909858491 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] 16:19:51 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:60827/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60827/tests/dromaeo/cssquery-ext.html) 16:19:54 INFO - PROCESS | 2228 | RSS: Main: 252874752 16:19:54 INFO - PROCESS | 2228 | 16:22:56 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:60827/tests/dromaeo/cssquery-ext.html (next: http://localhost:60827/tests/dromaeo/cssquery-jquery.html) 16:22:57 INFO - PROCESS | 2228 | RSS: Main: 265494528 16:22:57 INFO - PROCESS | 2228 | 16:25:59 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:60827/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60827/tests/dromaeo/cssquery-mootools.html) 16:25:59 INFO - PROCESS | 2228 | RSS: Main: 269783040 16:25:59 INFO - PROCESS | 2228 | 16:29:01 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:60827/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60827/tests/dromaeo/cssquery-prototype.html) 16:29:02 INFO - PROCESS | 2228 | RSS: Main: 268820480 16:29:02 INFO - PROCESS | 2228 | 16:32:05 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:60827/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60827/tests/dromaeo/cssquery-yui.html) 16:32:05 INFO - PROCESS | 2228 | RSS: Main: 276303872 16:32:05 INFO - PROCESS | 2228 | 16:35:08 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:60827/tests/dromaeo/cssquery-yui.html (next: http://localhost:60827/tests/dromaeo/cssquery-dojo.html) 16:35:08 INFO - PROCESS | 2228 | RSS: Main: 278315008 16:35:08 INFO - PROCESS | 2228 | 16:35:08 INFO - PROCESS | 2228 | __start_tp_report 16:35:08 INFO - PROCESS | 2228 | _x_x_mozilla_page_load 16:35:08 INFO - PROCESS | 2228 | _x_x_mozilla_page_load_details 16:35:08 INFO - PROCESS | 2228 | |i|pagename|runs| 16:35:08 INFO - PROCESS | 2228 | |0;dojo.html;2812.915612531625;3328.7669863109586;3337.149026998117;3356.110630682868;3381.273026299346;5751.798687045949;5803.535717142629;5813.128030795383;5845.853853653661;5887.8233652990375;5642.7178641067885;5744.023516002279;5746.1668058131545;5796.884062318751;5806.042003069489;5245.186996015615;5274.340707411574;5288.947110528884;5320.866978325535;5362.195670649395;5327.627066105355;5348.946510534904;5357.785688572452;5369.167778994242;5375.946240537603;5254.395744489375;5342.973285133569;5377.892442151155;5381.973090134564;5398.973005134969;5321.787128514855;5379.81170659027;5393.865153371158;5395.379531353905;5395.73021348931;4171.874843754692;5241.266222728821;5261.710880834182;5262.342207224097;5335.33308336458;5031.597472202252;5621.437856214387;5844.386339434341;5982.521398288127;6029.306629737593;5540.279763630702;6214.067889816519;6241.34465881085;6269.122322874801;6303.086052522359;5323.920141197885;6157.353477884847;6277.152584401093;6277.435030847246;6299.68501574924;5905.586608937376;5930.080837470199;5950.851228719273;5968.22413086296;5990.97004514979;5197.818076367309;6178.814635560941;6216.595921265103;6254.781082662176;6269.279032911182;6221.471174950089;6229.719662615194;6233.158523599347;6257.342978987232;6273.749050038039;5513.338399392019;6236.158118654014;6291.370862913672;6298.401651843067;6324.146240257598;4813.638977076734;5421.620486565903;5780.277465316835;6137.938620613737;6331.873362532723;6127.969360153171;6144.863200307957;6160.599561028519;6202.472789812917;6280.874382512324;3998.8600398985895;5005.424376196711;5005.974970125126;5032.220005899091;5033.245013247969;4453.22068637987;4463.709858859163;4493.4607847057905;4498.167838949805;4522.412086428743;5833.241678581842;5894.440028197314;5915.645061296336;5978.222831031938;6025.096235564701;6168.321484636686;6264.9976003839165;6293.244810622755;6297.685115744194;6302.590331628429;4836.371271734652;5834.416558344216;6005.129256257818;6015.969920150371;6130.969345153246;5444.8094316698725;5465.180222966586;5504.724763761876;5509.3664228614225;5572.860678482989;6185.072239164071;6257.342978987141;6264.780732674334;6281.434670879643;6346.524010699309;6143.634700640197;6259.499240060693;6288.874222515616;6294.118823364641;6294.874102518015;6400.039994000937;6400.359964003563;6412.935870641234;6425.710843011982;6442.613443193423;6125.816225513242;6145.617236121837;6227.221597300338;6240.157578726814;6261.843453913506;6193.194884664965;6207.193064901534;6237.96881015592;6240.469560087444;6260.718267677874;6168.0439531872635;6173.135760993554;6221.4089661482085;6241.500679945684;6248.094026366242;5930.703464826655;5945.019071853096;6179.887620228402;6197.81406557786;6230.345813689497;5860.912086318794;5959.612625179349;5975.641461512323;5981.880362392641;6059.515238780798;6114.114890844268;6126.914660831567;6128.655903792168;6146.555559443616;6162.075688646739;6099.84750381244;6105.186826067751;6106.339326077157;6109.556495504338;6113.689152827286 16:35:08 INFO - PROCESS | 2228 | |1;ext.html;448.5492080459139;451.6522277846059;456.0377603257129;478.95449932256435;518.1321286844534;36667.71662991796;38606.61393386065;38687.22625547491;38793.80603096988;38907.416388754194;35491.112722181955;37406.62593374065;38602.03494912628;39184;39323;8316.708915187985;9333.300002499805;9340.346175767665;9342.766430839249;9361.438313701166;9239.815203695956;9285.071492850695;9355.111264429868;9356.251499880027;9381.577828997677;9341.766455838624;9372.8594071089;9385.95307023464;9391.107844754737;9411.95294023529;9276.11876871696;9278.907210927871;9323;9341.486218257993;9395.765105872408;9104.817903641957;9113.544322783835;9139.634414623408;9226.723198303995;9229.538523073887;38010.80994595037;38044.61955380438;38239;38262.80868595639;38336.61663383358;3118.7660925430455;3177.2215807127172;3240.189952511872;3319.452290372086;3397.2356219850585;28600.570991435146;29081.272968175752;29163.125106246847;29289.70710292891;29315.560266596018;3156.1478400831875;3181.1887968567867;3206.4549026665522;3276.639569647337;3286.917827054319;474720;482936;482973.9999999965;483063;483085;46321.073578528914;55815.69750873661;56035.43964560384;56134.15798763022;56334;58632.7068364664;59304.40695592989;59400;59504;59518;15104.924475377553;15280.541583752505;15310.081395116329;15366.923165384102;15388.692226155414;11750.647480575597;11821.940890295493;11865.762684746256;11901.583444579572;11914.404279785977;15089.698206035819;15674.764878526832;15740.370385184693;15746.842531574537;15781.28984195714;6359.728054389141;6629.303923088103;6710.597364158166;6723.764668236686;6754.729810807611;811.8701007838717;834.5034704351023;973.6397532912725;1004.2417974429259;1065.9946700266448;28937.710622893923;28939.710602894123;29194.562081568794;29461;29932.850335748182;935.3452583191657;951.9952400237954;1012.3318609717551;1023.2683631203577;1032.7934413117407;14586;15139.772903406458;15147.166905820286;15388.461403850812;15469.922650386676;601209;606143;610026;612408;614142;55017.1747423789;55451;55480.44519554834;55570.44429555733;55702;1091.9945400272948;1303.3157592264138;1329.3419757220238;1338.4980632262902;1362.0805955979674;27992.020279290125;28779.28051798722;29044.75364804306;29174;29393;45653.771731142464;48176.75911620419;48978.75510622424;49096.50903490919;49255.50744492509;603642;606138.9079163905;606861;608194;608385;25295;25563.744362556135;25641.61537576975;25665.61501577515;25691.486170276115;43505;43530.34704479499;44256.11487770162;44649.33026004548;45073.54926450825;32630.34739305248;36992.26015479622;37577.4363384544;37642.741435513955;38037.80981095077;36430.4535431974;36437.45343819898;37348.43977340397;37726.43410348792;37762.43356349712;29047.273818154714;35514.467282990256;36456.17719113981;36574.085647859014;36639.816800915825;26280.73719262783;34053.31893362168;34903.30193396169;35023.47464787979;35197.82401087978;22326.77673223247;23157.536849262582;23621.881890590437;24734.876325618257;25356.492870142123 16:35:08 INFO - PROCESS | 2228 | |2;jquery.html;327.139622792057;358.7050746804037;364.7847769815808;372.24805892097953;375.073568286333;18242.452726418196;18904.716429253556;19487;19929.800701992994;20209.292674756387;19394.22423103074;19451.90274048631;20003.599928001433;20050.49873753157;20087.09608067637;5615.80344687936;5814.447627475404;5901.321348044966;5902.055671092626;5902.2622172228475;5354.785808567653;5647.717614119299;5708.257926469553;5762.74067666956;5770;5644.633098848582;5734.13987901816;5749.195112684228;5751.367349591542;5759.740811663485;5988.311344195408;6031;6069.089636554508;6077.726502307407;6089.299730530979;5470.17947307903;5545.556355491592;5634.239377684001;5729.713514324267;5857.355690874;23579.646305305436;23773.524529509312;24181.274561763174;24362;24748.876255618787;2006.3078238007863;2050.866693664907;2133.797289257518;2261.7625149359246;2421.2615152378517;9905.653302134391;10113.797724045478;10221.591136354537;10265.692029239135;11992.580259690956;1790.5613124784381;1852.5924296654714;1854.3695143651223;1873.9812601873944;1902.286642509059;116902;119455.40272298583;119561;119826;120839.39580302042;9070.81858362829;11091.44542772858;11148.219624626361;11462.254953428;11548.711282217844;13842.93078534621;14066.929665351608;14067.577972660632;14068.929655351658;14090.365933533016;6797.2523022467485;8181.222783835526;8199.7950051248;8292.792680182924;8297.336213103057;8027.15714849944;8057.677692892219;8064.717734879251;8234.876476852853;8317.792055198548;4297.383745590134;4410.184115938498;4430.56006797792;4503.932441013388;4508.752018639007;1847.3718935561974;1907.9523011924534;1912.8660993730584;1914.9712754308696;1915.961680766377;771.0824119298129;801.3949468151508;850.1795767084793;893.2942975049768;931.8462453695064;18838.246470141316;19552;20037.899810500854;20525;20587.795616946143;876.8202518483696;897.2552781191596;905.7282815155427;918.3617385916724;919.6689191890914;8857.822843543221;10442.68671939843;10728.409937453358;10756.354618722902;10781.676549703521;483820;489539.5523022362;491267;593961;596517;119038.80961190451;125610;128575.35712321565;129508;131438;997.7006897930737;1002.9849552256723;1060.4591658254192;1066.4134725900724;1068.7221322456217;10665.89334106649;11413.885861141614;15737.504937030964;16245.59386015359;18159.546011349823;45383.773081134386;54893.17660235179;55130.44869551253;55681.44318556762;56419;77425.61287193529;82208.17791822006;82536;83925.58037209774;84762.1523784779;11723.41382930867;12097.395130243629;12156.392180391122;12182.939085304872;12612.684682883002;7201.855962880818;11636.825447618461;13030.934845325713;13477.506661267696;13596.728065438834;11089.445527723743;11392.031677307521;11691.181617286707;11693.532258709556;11995.520179193083;11502.88497115018;11594.478248479007;11658.88341116612;11761.823572646246;12077.27536347822;7649.273319034683;7878.921210787818;8200.138985406686;8280.958595206985;9340.299477539076;12057.03543716483;12106.394680266128;12183.512659493521;12425.751484970431;12550.811737824122;9236.676716314896;9892.971940752272;11510.48202830858;11701.531938722355;11849.703757406134 16:35:08 INFO - PROCESS | 2228 | |3;mootools.html;1183.1717797541721;1384.5984664447308;1572.3710515793682;1736.4964160393486;1818.5635447492598;7063.752768653098;7608.657610407531;7910.604469776503;8629.223369896708;8661.78345541361;8073.596320183997;8406.91593084069;8444.704435344764;8535.146485351477;8538.06081331053;2259.3899647095272;2288.1533832482005;2299.1608063056947;2300.3559003479054;2304.4238940264936;2274.9772502275014;2316.0388438797963;2333.9066437342485;2335.1476711000446;2345.8592484450987;2268.9092436302526;2277.237125562938;2303.5623231585946;2307.4462129088984;2316.6177580699164;2268.1834539565743;2268.274152271274;2285.0631241191113;2290.1068583252545;2295.9553403201585;2286.9708631116064;2308.2728940383895;2308.6883270758567;2319.22306027481;2321.7097862767155;8387.119352467964;8424.157584241588;8481.703140390118;8486.363522735819;8497.192766687154;5032.874178145576;5187.144121220032;5192.948070519285;5197.584193264567;5205.427402985669;3178.995848922769;3240.659730728263;3521.066917266901;3527.4708793680898;3534.452159915217;1005.2661557063385;1025.887152413234;1041.8541404203418;1057.740853490892;1061.4321338084087;11010.779784404347;11028.44857757109;11059.447027648584;11127.33236005834;11145.498452569655;4444.4888837784065;4526.932096018562;4593.678442509059;4608.654350923694;4686.531346865354;9455.621775128919;9522.428654280766;9536.046395360547;9541.47521886303;9562.28282878787;5215.217717342429;5219.399769026534;5221.895562088737;5231.372235331784;5234.790608375699;4287.3140297552245;4300.4839419269265;4720.693154944917;4766.499517550607;4775.4269487661;3264.9347013059605;3726.5341832270965;3774.1319496515957;3811.847526098925;3852.8266228019806;1268.689171153055;1276.8212450256972;1278.8337516122845;1290.1291628151148;1290.6321698315935;805.5287656720767;875.6978842299396;894.6197865906963;920.5719340506696;931.0940850762436;5510.91733623996;5669.914951275734;5783.88432231353;5846.941530584725;5868.119782032644;904.5884122724144;924.3483344242293;936.990630093704;937.8417654197061;977.9266555008403;3821.38857782759;3839.8080095995647;3846.519185101862;3957.6438120569287;3967.2858885400906;7979.3217576505485;10268.589256429808;10293.176545876311;10296.845547316796;10331.121854642439;8609.397342185986;10043.995600439897;10060.849087263698;10246.743831404125;10280.794384112425;7488.812779680443;7795.493292935941;7851.960740196262;7863.921360786433;7887.329576985906;5027.74861256943;5745.425457454221;5798.623089499169;5856.531477481876;5948.078047902582;9015.774605634911;9022.458652480609;9359.39163954368;9467.621295148117;9479.952600237231;8698.521581312836;9383.73319601845;9432.669856554987;9645.131938125602;9691.224702023963;6660.200775906922;6883.414909732729;7039.049728286821;7122.109736282965;7263.818404539928;7963.880541791762;8118.18818118207;8141.674333026613;8156.551389673387;8186.631601578062;7012.1935977363255;7136.286371362822;7247.927520724726;7317.304856038668;7361.742339018108;6903.206131294965;7109.28907109285;7110.7511237106455;7205.459590530836;7218.556288742167;6708.6310252936555;6737.494687898526;6805.081314022544;6806.489513286622;6812.14848143982;4528.977355113203;4838.322634831196;4901.754912254444;4921.2864134700985;4933.777979990838;4790.305405716222;4875.414950205998;4894.898647804215;4918.729469879191;4985.900281994267 16:35:08 INFO - PROCESS | 2228 | |4;prototype.html;255.27756449248628;298.53130584981574;306.0954878334522;306.2023428967538;310.3002728846451;4572.451305843299;4584.195118052017;4849.247737217524;5454.354638000566;5573.708244651621;5256.26412302278;5431.891362172754;5452.972735136329;5476.698781567012;5556.611037227396;3556.982215088934;4179.289520781459;4206.810693518792;4232.49210094789;4239.173361194568;3752.2683076800035;3847.9615203847884;4251.787410629471;4252.9792849716;4258.444933040182;3896.8504291234153;3922.6270805218232;4048.75707457552;4113.305784711019;4151.422459465399;3009.653889802679;3216.4371235033777;3487.9361794652623;3639.5632524097014;4030.7504673551293;2914.111196085193;3069.3247485553143;3120.92328146789;3564.7682900611635;3584.22939068099;4162.979185104085;5152.871178220537;5202.557782588485;5246.081935661244;5436.933965093239;4088.8160032798596;4639.930401043987;4648.279516674921;4669.6497762667595;4741.95258047422;4829.830955916526;4969.757560609847;5716.799912003102;5720.256366672306;5770.855728606818;3582.731295152874;3800.809959502014;3868.903277418059;3885.7668539887413;3895.220955808821;5768.913466298009;5971.850703732442;5972.402005127783;6094.756209751649;6120.693965301762;3897.824597893102;3900.473436086149;3901.785401802928;3914.393269043303;3927.7446965947124;3526.823658817049;3806.2958352704836;3919.5884432134217;3952.26883026641;3975.344068228768;4592.747398893093;4613.308003799457;4614.953850461519;4671.5328467153695;4702.717836929795;5791.7683292667825;5808.448197421239;5847.6783776891825;5855.941440585625;5885.47030767233;1115.7210697325668;1206.288289908958;1214.3017764785284;1249.6938250128776;1274.6112435707198;840.0129847429246;859.9011113721876;865.7402779166225;866.328595338605;869.5695630662736;4373.272557339833;5084.161113416245;5101.566366858784;5125.666831655931;5132.435432102466;5325.680459172462;5503.752331145108;5540.337898630395;5588.2455868458055;5621;4284.228838808982;4885.731284779371;4954.8513544593;5008.524190201925;5102.464241254689;2455.72986971433;2954.335274563206;2961.9703802962126;2975.0101148345775;3005.9549106763416;5247.475252474722;5673.687947162945;5777.451142141574;5943.970280148658;5957.731902064417;4630.6063984561015;5399.595030372738;5411.485908838733;5891.46976772084;6211.223597050369;5292.523672869537;5933.673647949404;6292.143817573827;6292.590981586182;6303.982725527787;5318.97340513295;5972.432618901197;6034.9094763577705;6063.423974722395;6122.7244773984385;5714.685692286964;5864.50758427658;5871.354151043467;5919.23050003514;5929.792457263975;5803.9419605803405;6102.511799055976;6186.566940314135;6251.530890240881;6344.809655710336;3723.3670276052594;3867.5939026401825;3869.9234108882943;3909.941350879796;3916.942425545144;5026.894083301668;5327.174287985368;5943.970280148572;6042.546808989431;6052.939470605414;5601.80393686219;5605.757696921264;5788.176724331822;5834.38738932423;5863.442972917566;4803.831865884677;4991.975040124777;5299.6290259681455;5858.7070646468355;5892.705364731661;4861.440934292602;5442.727863606883;5611.438856114356;5697.8319444512945;5741.483266506035;5312.150055991023;5793.205450429975;5816.272965879265;5827.834433113309;5869.735861886311;5189.974050129877;5632.042552766122;5670.631408958405;5705.914411283917;5736.71316434185 16:35:08 INFO - PROCESS | 2228 | |5;yui.html;588.1325045557801;655.7737580534715;704.40125892991;740.3484933258728;801.3989507869098;13734.519291824789;14407.783883241747;14605.269736513184;14650.560483185494;14666.486672966448;12980.610581682564;13456.66358341042;13576.524821631245;13578.660533486667;13584.864151358483;723.4284914917235;824.5382585751968;834.9499030058186;845.750503601436;863.8876945997011;662.6189940784044;680.8706345794308;681.870444615524;842.2293601354753;846.9534175620338;871.9782005449882;884.4914174349742;885.0529932971723;885.0706757904208;886.3706768194589;876.2858270509546;884.1247165306364;884.9867251991225;886.0032463478586;886.8226354729047;649.3409189672486;652.8008957268031;656.5076192855358;656.9638669873156;658.6673729766485;12931.935340323238;13032.869671303355;13069.15050521713;13106.868931310755;13724.794128088086;11443.828342574869;13093.952483801273;13319.73360532784;13839.446422143103;13947.721045579032;9859.704208873745;11360.772784544346;11427.142964277713;11496;11699.883001169965;3590.7666001709804;3661.9450708239397;3773.8301776420126;3800.657940785343;3812.9809350953346;69288;88795;91477;93722;93860.0613993865;9364.391314564531;10553.261271710906;10635.680929572125;10739.301945373525;10866.130709543218;21117.26089586857;21170;21218.681719774217;21291.36125916225;21336.573268534543;7078;7276.745313913988;7402.1117465904435;7493.775186744407;7614.390848732088;4125.525564560114;4180.184863951561;4237.322028475429;4239.40648309237;4330.545292744279;3151.5115157150676;3211.0527394418705;3245.902622921316;3246.4967929970894;3252.10567094051;510.2677657561337;539.156220514893;607.2561112636985;609.0894113300644;621.0311913414997;490.5879061588283;590.2680675961776;599.3526990849872;600.0179109824167;622.1973653986397;8169.714060007871;8312.5428101455;8409.579521023925;8565.143485651384;8706.390552661253;508.5194491205849;515.3223511082914;562.361719448429;617.4227097663698;618.0821480102045;3985.262726395626;4133.23535145999;4186.665066794649;4187.392828039978;4210.179015092089;82251;104099.91800163954;113834.43082784532;115335.2699709505;115821.42089289668;100763;105598.47200763947;105696.47151764193;106660;107069;82949;83435.74846377187;83500.74748878772;85839;87149.56425217833;7917.2874441300555;7996.600169991593;8059.028939967004;8177.877331840146;8226.341892648454;42222.99999999877;45950.39173628907;48987.59111679966;54645;55208.791648335224;58403;74524.50950980841;79311.60344198473;80034;81587.18412815795;9875.40747555149;10060.547275372479;10162.542685579314;10178;10281.074703276741;9514.571844266886;10461.843072353768;11029.062529685067;11202.551897924317;11438.714032149262;7897.123155261935;8893.777655558662;9903.702888913343;10020.29464449639;10875.129989600655;10515.579376825148;10692.62575809874;10862.402567858528;10992.505337259683;11108.555657773923;10644;11054;12229.87770122323;12657.303848288433;12813.103082784115;10480.842787358348;10587.894121058998;10644.361338319726;10737.946310268399;11216.943915280372;7594.582297973665;9053.818923621622;10828.945855270673;11103.056240219348;11154.442277886235 16:35:08 INFO - PROCESS | 2228 | __end_tp_report 16:35:08 INFO - PROCESS | 2228 | __start_cc_report 16:35:08 INFO - PROCESS | 2228 | _x_x_mozilla_cycle_collect,376 16:35:08 INFO - PROCESS | 2228 | __end_cc_report 16:35:08 INFO - PROCESS | 2228 | __startTimestamp1478910908976__endTimestamp 16:35:08 INFO - PROCESS | 2228 | 16:35:08 INFO - PROCESS | 2228 | ------- Summary: start ------- 16:35:08 INFO - PROCESS | 2228 | Number of tests: 6 16:35:08 INFO - PROCESS | 2228 | 16:35:08 INFO - PROCESS | 2228 | [#0] dojo.html Cycles:165 Average:5757.71 Median:5990.97 stddev:672.87 (11.2%) stddev-sans-first:634.04 16:35:08 INFO - PROCESS | 2228 | Values: 2812.9 3328.8 3337.1 3356.1 3381.3 5751.8 5803.5 5813.1 5845.9 5887.8 5642.7 5744.0 5746.2 5796.9 5806.0 5245.2 5274.3 5288.9 5320.9 5362.2 5327.6 5348.9 5357.8 5369.2 5375.9 5254.4 5343.0 5377.9 5382.0 5399.0 5321.8 5379.8 5393.9 5395.4 5395.7 4171.9 5241.3 5261.7 5262.3 5335.3 5031.6 5621.4 5844.4 5982.5 6029.3 5540.3 6214.1 6241.3 6269.1 6303.1 5323.9 6157.4 6277.2 6277.4 6299.7 5905.6 5930.1 5950.9 5968.2 5991.0 5197.8 6178.8 6216.6 6254.8 6269.3 6221.5 6229.7 6233.2 6257.3 6273.7 5513.3 6236.2 6291.4 6298.4 6324.1 4813.6 5421.6 5780.3 6137.9 6331.9 6128.0 6144.9 6160.6 6202.5 6280.9 3998.9 5005.4 5006.0 5032.2 5033.2 4453.2 4463.7 4493.5 4498.2 4522.4 5833.2 5894.4 5915.6 5978.2 6025.1 6168.3 6265.0 6293.2 6297.7 6302.6 4836.4 5834.4 6005.1 6016.0 6131.0 5444.8 5465.2 5504.7 5509.4 5572.9 6185.1 6257.3 6264.8 6281.4 6346.5 6143.6 6259.5 6288.9 6294.1 6294.9 6400.0 6400.4 6412.9 6425.7 6442.6 6125.8 6145.6 6227.2 6240.2 6261.8 6193.2 6207.2 6238.0 6240.5 6260.7 6168.0 6173.1 6221.4 6241.5 6248.1 5930.7 5945.0 6179.9 6197.8 6230.3 5860.9 5959.6 5975.6 5981.9 6059.5 6114.1 6126.9 6128.7 6146.6 6162.1 6099.8 6105.2 6106.3 6109.6 6113.7 16:35:08 INFO - PROCESS | 2228 | 16:35:08 INFO - PROCESS | 2228 | [#1] ext.html Cycles:180 Average:68664.42 Median:38250.90 stddev:152171.49 (397.8%) stddev-sans-first:152512.18 16:35:08 INFO - PROCESS | 2228 | Values: 448.5 451.7 456.0 479.0 518.1 36667.7 38606.6 38687.2 38793.8 38907.4 35491.1 37406.6 38602.0 39184.0 39323.0 8316.7 9333.3 9340.3 9342.8 9361.4 9239.8 9285.1 9355.1 9356.3 9381.6 9341.8 9372.9 9386.0 9391.1 9412.0 9276.1 9278.9 9323.0 9341.5 9395.8 9104.8 9113.5 9139.6 9226.7 9229.5 38010.8 38044.6 38239.0 38262.8 38336.6 3118.8 3177.2 3240.2 3319.5 3397.2 28600.6 29081.3 29163.1 29289.7 29315.6 3156.1 3181.2 3206.5 3276.6 3286.9 474720.0 482936.0 482974.0 483063.0 483085.0 46321.1 55815.7 56035.4 56134.2 56334.0 58632.7 59304.4 59400.0 59504.0 59518.0 15104.9 15280.5 15310.1 15366.9 15388.7 11750.6 11821.9 11865.8 11901.6 11914.4 15089.7 15674.8 15740.4 15746.8 15781.3 6359.7 6629.3 6710.6 6723.8 6754.7 811.9 834.5 973.6 1004.2 1066.0 28937.7 28939.7 29194.6 29461.0 29932.9 935.3 952.0 1012.3 1023.3 1032.8 14586.0 15139.8 15147.2 15388.5 15469.9 601209.0 606143.0 610026.0 612408.0 614142.0 55017.2 55451.0 55480.4 55570.4 55702.0 1092.0 1303.3 1329.3 1338.5 1362.1 27992.0 28779.3 29044.8 29174.0 29393.0 45653.8 48176.8 48978.8 49096.5 49255.5 603642.0 606138.9 606861.0 608194.0 608385.0 25295.0 25563.7 25641.6 25665.6 25691.5 43505.0 43530.3 44256.1 44649.3 45073.5 32630.3 36992.3 37577.4 37642.7 38037.8 36430.5 36437.5 37348.4 37726.4 37762.4 29047.3 35514.5 36456.2 36574.1 36639.8 26280.7 34053.3 34903.3 35023.5 35197.8 22326.8 23157.5 23621.9 24734.9 25356.5 16:35:08 INFO - PROCESS | 2228 | 16:35:08 INFO - PROCESS | 2228 | [#2] jquery.html Cycles:180 Average:33147.19 Median:2197.78 stddev:89831.64 (4087.4%) stddev-sans-first:90049.84 16:35:08 INFO - PROCESS | 2228 | Values: 327.1 358.7 364.8 372.2 375.1 18242.5 18904.7 19487.0 19929.8 20209.3 19394.2 19451.9 20003.6 20050.5 20087.1 5615.8 5814.4 5901.3 5902.1 5902.3 5354.8 5647.7 5708.3 5762.7 5770.0 5644.6 5734.1 5749.2 5751.4 5759.7 5988.3 6031.0 6069.1 6077.7 6089.3 5470.2 5545.6 5634.2 5729.7 5857.4 23579.6 23773.5 24181.3 24362.0 24748.9 2006.3 2050.9 2133.8 2261.8 2421.3 9905.7 10113.8 10221.6 10265.7 11992.6 1790.6 1852.6 1854.4 1874.0 1902.3 116902.0 119455.4 119561.0 119826.0 120839.4 9070.8 11091.4 11148.2 11462.3 11548.7 13842.9 14066.9 14067.6 14068.9 14090.4 6797.3 8181.2 8199.8 8292.8 8297.3 8027.2 8057.7 8064.7 8234.9 8317.8 4297.4 4410.2 4430.6 4503.9 4508.8 1847.4 1908.0 1912.9 1915.0 1916.0 771.1 801.4 850.2 893.3 931.8 18838.2 19552.0 20037.9 20525.0 20587.8 876.8 897.3 905.7 918.4 919.7 8857.8 10442.7 10728.4 10756.4 10781.7 483820.0 489539.6 491267.0 593961.0 596517.0 119038.8 125610.0 128575.4 129508.0 131438.0 997.7 1003.0 1060.5 1066.4 1068.7 10665.9 11413.9 15737.5 16245.6 18159.5 45383.8 54893.2 55130.4 55681.4 56419.0 77425.6 82208.2 82536.0 83925.6 84762.2 11723.4 12097.4 12156.4 12182.9 12612.7 7201.9 11636.8 13030.9 13477.5 13596.7 11089.4 11392.0 11691.2 11693.5 11995.5 11502.9 11594.5 11658.9 11761.8 12077.3 7649.3 7878.9 8200.1 8281.0 9340.3 12057.0 12106.4 12183.5 12425.8 12550.8 9236.7 9893.0 11510.5 11701.5 11849.7 16:35:08 INFO - PROCESS | 2228 | 16:35:08 INFO - PROCESS | 2228 | [#3] mootools.html Cycles:180 Average:5459.48 Median:5190.05 stddev:2993.91 (57.7%) stddev-sans-first:2985.06 16:35:08 INFO - PROCESS | 2228 | Values: 1183.2 1384.6 1572.4 1736.5 1818.6 7063.8 7608.7 7910.6 8629.2 8661.8 8073.6 8406.9 8444.7 8535.1 8538.1 2259.4 2288.2 2299.2 2300.4 2304.4 2275.0 2316.0 2333.9 2335.1 2345.9 2268.9 2277.2 2303.6 2307.4 2316.6 2268.2 2268.3 2285.1 2290.1 2296.0 2287.0 2308.3 2308.7 2319.2 2321.7 8387.1 8424.2 8481.7 8486.4 8497.2 5032.9 5187.1 5192.9 5197.6 5205.4 3179.0 3240.7 3521.1 3527.5 3534.5 1005.3 1025.9 1041.9 1057.7 1061.4 11010.8 11028.4 11059.4 11127.3 11145.5 4444.5 4526.9 4593.7 4608.7 4686.5 9455.6 9522.4 9536.0 9541.5 9562.3 5215.2 5219.4 5221.9 5231.4 5234.8 4287.3 4300.5 4720.7 4766.5 4775.4 3264.9 3726.5 3774.1 3811.8 3852.8 1268.7 1276.8 1278.8 1290.1 1290.6 805.5 875.7 894.6 920.6 931.1 5510.9 5669.9 5783.9 5846.9 5868.1 904.6 924.3 937.0 937.8 977.9 3821.4 3839.8 3846.5 3957.6 3967.3 7979.3 10268.6 10293.2 10296.8 10331.1 8609.4 10044.0 10060.8 10246.7 10280.8 7488.8 7795.5 7852.0 7863.9 7887.3 5027.7 5745.4 5798.6 5856.5 5948.1 9015.8 9022.5 9359.4 9467.6 9480.0 8698.5 9383.7 9432.7 9645.1 9691.2 6660.2 6883.4 7039.0 7122.1 7263.8 7963.9 8118.2 8141.7 8156.6 8186.6 7012.2 7136.3 7247.9 7317.3 7361.7 6903.2 7109.3 7110.8 7205.5 7218.6 6708.6 6737.5 6805.1 6806.5 6812.1 4529.0 4838.3 4901.8 4921.3 4933.8 4790.3 4875.4 4894.9 4918.7 4985.9 16:35:08 INFO - PROCESS | 2228 | 16:35:08 INFO - PROCESS | 2228 | [#4] prototype.html Cycles:180 Average:4597.44 Median:5142.65 stddev:1472.49 (28.6%) stddev-sans-first:1440.10 16:35:08 INFO - PROCESS | 2228 | Values: 255.3 298.5 306.1 306.2 310.3 4572.5 4584.2 4849.2 5454.4 5573.7 5256.3 5431.9 5453.0 5476.7 5556.6 3557.0 4179.3 4206.8 4232.5 4239.2 3752.3 3848.0 4251.8 4253.0 4258.4 3896.9 3922.6 4048.8 4113.3 4151.4 3009.7 3216.4 3487.9 3639.6 4030.8 2914.1 3069.3 3120.9 3564.8 3584.2 4163.0 5152.9 5202.6 5246.1 5436.9 4088.8 4639.9 4648.3 4669.6 4742.0 4829.8 4969.8 5716.8 5720.3 5770.9 3582.7 3800.8 3868.9 3885.8 3895.2 5768.9 5971.9 5972.4 6094.8 6120.7 3897.8 3900.5 3901.8 3914.4 3927.7 3526.8 3806.3 3919.6 3952.3 3975.3 4592.7 4613.3 4615.0 4671.5 4702.7 5791.8 5808.4 5847.7 5855.9 5885.5 1115.7 1206.3 1214.3 1249.7 1274.6 840.0 859.9 865.7 866.3 869.6 4373.3 5084.2 5101.6 5125.7 5132.4 5325.7 5503.8 5540.3 5588.2 5621.0 4284.2 4885.7 4954.9 5008.5 5102.5 2455.7 2954.3 2962.0 2975.0 3006.0 5247.5 5673.7 5777.5 5944.0 5957.7 4630.6 5399.6 5411.5 5891.5 6211.2 5292.5 5933.7 6292.1 6292.6 6304.0 5319.0 5972.4 6034.9 6063.4 6122.7 5714.7 5864.5 5871.4 5919.2 5929.8 5803.9 6102.5 6186.6 6251.5 6344.8 3723.4 3867.6 3869.9 3909.9 3916.9 5026.9 5327.2 5944.0 6042.5 6052.9 5601.8 5605.8 5788.2 5834.4 5863.4 4803.8 4992.0 5299.6 5858.7 5892.7 4861.4 5442.7 5611.4 5697.8 5741.5 5312.2 5793.2 5816.3 5827.8 5869.7 5190.0 5632.0 5670.6 5705.9 5736.7 16:35:08 INFO - PROCESS | 2228 | 16:35:08 INFO - PROCESS | 2228 | [#5] yui.html Cycles:180 Average:20109.21 Median:25140.47 stddev:30560.31 (121.6%) stddev-sans-first:30610.89 16:35:08 INFO - PROCESS | 2228 | Values: 588.1 655.8 704.4 740.3 801.4 13734.5 14407.8 14605.3 14650.6 14666.5 12980.6 13456.7 13576.5 13578.7 13584.9 723.4 824.5 834.9 845.8 863.9 662.6 680.9 681.9 842.2 847.0 872.0 884.5 885.1 885.1 886.4 876.3 884.1 885.0 886.0 886.8 649.3 652.8 656.5 657.0 658.7 12931.9 13032.9 13069.2 13106.9 13724.8 11443.8 13094.0 13319.7 13839.4 13947.7 9859.7 11360.8 11427.1 11496.0 11699.9 3590.8 3661.9 3773.8 3800.7 3813.0 69288.0 88795.0 91477.0 93722.0 93860.1 9364.4 10553.3 10635.7 10739.3 10866.1 21117.3 21170.0 21218.7 21291.4 21336.6 7078.0 7276.7 7402.1 7493.8 7614.4 4125.5 4180.2 4237.3 4239.4 4330.5 3151.5 3211.1 3245.9 3246.5 3252.1 510.3 539.2 607.3 609.1 621.0 490.6 590.3 599.4 600.0 622.2 8169.7 8312.5 8409.6 8565.1 8706.4 508.5 515.3 562.4 617.4 618.1 3985.3 4133.2 4186.7 4187.4 4210.2 82251.0 104099.9 113834.4 115335.3 115821.4 100763.0 105598.5 105696.5 106660.0 107069.0 82949.0 83435.7 83500.7 85839.0 87149.6 7917.3 7996.6 8059.0 8177.9 8226.3 42223.0 45950.4 48987.6 54645.0 55208.8 58403.0 74524.5 79311.6 80034.0 81587.2 9875.4 10060.5 10162.5 10178.0 10281.1 9514.6 10461.8 11029.1 11202.6 11438.7 7897.1 8893.8 9903.7 10020.3 10875.1 10515.6 10692.6 10862.4 10992.5 11108.6 10644.0 11054.0 12229.9 12657.3 12813.1 10480.8 10587.9 10644.4 10737.9 11216.9 7594.6 9053.8 10828.9 11103.1 11154.4 16:35:08 INFO - PROCESS | 2228 | -------- Summary: end -------- 16:35:08 INFO - PROCESS | 2228 | 16:35:09 INFO - TEST-INFO | 2228: exit 0 16:35:09 INFO - TEST-OK | dromaeo_css | took 1116921ms 16:35:09 INFO - TEST-START | kraken 16:35:09 INFO - operating with platform_type : mac_ 16:35:09 INFO - Initialising browser for kraken test... 16:35:09 INFO - TEST-INFO | started process 2233 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmps2dY1_/profile http://localhost:60827/getInfo.html) 16:35:12 INFO - PROCESS | 2233 | 2016-11-11 16:35:12.532 plugin-container[2234:28509] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.tsm.portname' 16:35:12 INFO - PROCESS | 2233 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:35:12 INFO - PROCESS | 2233 | 2016-11-11 16:35:12.535 plugin-container[2234:28509] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 16:35:12 INFO - PROCESS | 2233 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:35:12 INFO - PROCESS | 2233 | 2016-11-11 16:35:12.535 plugin-container[2234:28509] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:35:12 INFO - PROCESS | 2233 | __metrics Screen width/height:1600/1200 16:35:12 INFO - PROCESS | 2233 | colorDepth:24 16:35:12 INFO - PROCESS | 2233 | Browser inner width/height: 1024/689 16:35:12 INFO - PROCESS | 2233 | __metrics 16:35:12 ERROR - PROCESS | 2233 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 16:35:13 INFO - TEST-INFO | 2233: exit 0 16:35:13 INFO - Browser initialized. 16:35:13 INFO - Running cycle 1/1 for kraken test... 16:35:13 INFO - TEST-INFO | started process 2235 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmps2dY1_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:35:15 INFO - PROCESS | 2235 | 2016-11-11 16:35:15.710 plugin-container[2236:28626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 16:35:15 INFO - PROCESS | 2235 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:35:15 INFO - PROCESS | 2235 | 2016-11-11 16:35:15.710 plugin-container[2236:28626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 16:35:15 INFO - PROCESS | 2235 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:35:15 INFO - PROCESS | 2235 | 2016-11-11 16:35:15.710 plugin-container[2236:28626] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:35:25 INFO - PROCESS | 2235 | RSS: Main: 237531136 16:35:25 INFO - PROCESS | 2235 | 16:35:28 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:60827/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60827/tests/kraken/driver.html?testName=audio-beat-detection) 16:35:29 INFO - PROCESS | 2235 | RSS: Main: 249999360 16:35:29 INFO - PROCESS | 2235 | 16:35:31 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:60827/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60827/tests/kraken/driver.html?testName=audio-dft) 16:35:32 INFO - PROCESS | 2235 | RSS: Main: 257744896 16:35:32 INFO - PROCESS | 2235 | 16:35:36 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:60827/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60827/tests/kraken/driver.html?testName=audio-fft) 16:35:36 INFO - PROCESS | 2235 | RSS: Main: 260427776 16:35:36 INFO - PROCESS | 2235 | 16:35:38 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:60827/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60827/tests/kraken/driver.html?testName=audio-oscillator) 16:35:39 INFO - PROCESS | 2235 | RSS: Main: 261550080 16:35:39 INFO - PROCESS | 2235 | 16:35:41 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:60827/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60827/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:35:42 INFO - PROCESS | 2235 | RSS: Main: 263208960 16:35:42 INFO - PROCESS | 2235 | 16:35:45 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:60827/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60827/tests/kraken/driver.html?testName=imaging-darkroom) 16:35:45 INFO - PROCESS | 2235 | RSS: Main: 269291520 16:35:45 INFO - PROCESS | 2235 | 16:35:48 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:60827/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60827/tests/kraken/driver.html?testName=imaging-desaturate) 16:35:49 INFO - PROCESS | 2235 | RSS: Main: 268271616 16:35:49 INFO - PROCESS | 2235 | 16:35:52 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:60827/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60827/tests/kraken/driver.html?testName=json-parse-financial) 16:35:52 INFO - PROCESS | 2235 | RSS: Main: 268525568 16:35:52 INFO - PROCESS | 2235 | 16:35:54 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:60827/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60827/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:35:55 INFO - PROCESS | 2235 | RSS: Main: 267550720 16:35:55 INFO - PROCESS | 2235 | 16:35:57 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:60827/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60827/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:35:57 INFO - PROCESS | 2235 | RSS: Main: 267632640 16:35:57 INFO - PROCESS | 2235 | 16:35:59 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:60827/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60827/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:36:00 INFO - PROCESS | 2235 | RSS: Main: 267685888 16:36:00 INFO - PROCESS | 2235 | 16:36:03 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:60827/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60827/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:36:04 INFO - PROCESS | 2235 | RSS: Main: 268062720 16:36:04 INFO - PROCESS | 2235 | 16:36:07 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:60827/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60827/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:36:08 INFO - PROCESS | 2235 | RSS: Main: 267829248 16:36:08 INFO - PROCESS | 2235 | 16:36:09 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:60827/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60827/tests/kraken/driver.html?testName=ai-astar) 16:36:10 INFO - PROCESS | 2235 | RSS: Main: 269688832 16:36:10 INFO - PROCESS | 2235 | 16:36:10 INFO - PROCESS | 2235 | __start_tp_report 16:36:10 INFO - PROCESS | 2235 | _x_x_mozilla_page_load 16:36:10 INFO - PROCESS | 2235 | _x_x_mozilla_page_load_details 16:36:10 INFO - PROCESS | 2235 | |i|pagename|runs| 16:36:10 INFO - PROCESS | 2235 | |0;ai-astar;114;119;160;112;103;131;173;177;120;120 16:36:10 INFO - PROCESS | 2235 | |1;audio-beat-detection;157;147;145;156;118;104;135;141;132;154 16:36:10 INFO - PROCESS | 2235 | |2;audio-dft;199;207;278;269;260;195;193;199;195;247 16:36:10 INFO - PROCESS | 2235 | |3;audio-fft;82;59;60;85;60;59;60;62;108;80 16:36:10 INFO - PROCESS | 2235 | |4;audio-oscillator;87;70;87;73;84;75;86;69;70;89 16:36:10 INFO - PROCESS | 2235 | |5;imaging-gaussian-blur;91;88;91;89;100;89;137;131;80;134 16:36:10 INFO - PROCESS | 2235 | |6;imaging-darkroom;96;96;95;96;94;153;123;95;96;96 16:36:10 INFO - PROCESS | 2235 | |7;imaging-desaturate;88;110;111;86;91;92;115;141;80;138 16:36:10 INFO - PROCESS | 2235 | |8;json-parse-financial;57;56;57;58;57;59;59;58;59;56 16:36:10 INFO - PROCESS | 2235 | |9;json-stringify-tinderbox;71;78;70;98;94;78;70;69;86;71 16:36:10 INFO - PROCESS | 2235 | |10;stanford-crypto-aes;119;88;91;88;87;85;87;93;90;94 16:36:10 INFO - PROCESS | 2235 | |11;stanford-crypto-ccm;158;155;146;148;148;148;149;184;229;195 16:36:10 INFO - PROCESS | 2235 | |12;stanford-crypto-pbkdf2;177;178;178;176;176;178;177;179;173;245 16:36:10 INFO - PROCESS | 2235 | |13;stanford-crypto-sha256-iterative;69;69;68;68;71;69;67;69;67;66 16:36:10 INFO - PROCESS | 2235 | __end_tp_report 16:36:10 INFO - PROCESS | 2235 | __start_cc_report 16:36:10 INFO - PROCESS | 2235 | _x_x_mozilla_cycle_collect,964 16:36:10 INFO - PROCESS | 2235 | __end_cc_report 16:36:10 INFO - PROCESS | 2235 | __startTimestamp1478910970016__endTimestamp 16:36:10 INFO - PROCESS | 2235 | 16:36:10 INFO - PROCESS | 2235 | ------- Summary: start ------- 16:36:10 INFO - PROCESS | 2235 | Number of tests: 14 16:36:10 INFO - PROCESS | 2235 | 16:36:10 INFO - PROCESS | 2235 | [#0] ai-astar Cycles:10 Average:132.90 Median:125.50 stddev:26.88 (21.4%) stddev-sans-first:27.63 16:36:10 INFO - PROCESS | 2235 | Values: 114.0 119.0 160.0 112.0 103.0 131.0 173.0 177.0 120.0 120.0 16:36:10 INFO - PROCESS | 2235 | 16:36:10 INFO - PROCESS | 2235 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:146.00 stddev:17.23 (11.8%) stddev-sans-first:16.99 16:36:10 INFO - PROCESS | 2235 | Values: 157.0 147.0 145.0 156.0 118.0 104.0 135.0 141.0 132.0 154.0 16:36:10 INFO - PROCESS | 2235 | 16:36:10 INFO - PROCESS | 2235 | [#2] audio-dft Cycles:10 Average:224.20 Median:227.00 stddev:34.88 (15.4%) stddev-sans-first:35.78 16:36:10 INFO - PROCESS | 2235 | Values: 199.0 207.0 278.0 269.0 260.0 195.0 193.0 199.0 195.0 247.0 16:36:10 INFO - PROCESS | 2235 | 16:36:10 INFO - PROCESS | 2235 | [#3] audio-fft Cycles:10 Average:71.50 Median:61.00 stddev:16.65 (27.3%) stddev-sans-first:17.23 16:36:10 INFO - PROCESS | 2235 | Values: 82.0 59.0 60.0 85.0 60.0 59.0 60.0 62.0 108.0 80.0 16:36:10 INFO - PROCESS | 2235 | 16:36:10 INFO - PROCESS | 2235 | [#4] audio-oscillator Cycles:10 Average:79.00 Median:85.00 stddev:8.27 (9.7%) stddev-sans-first:8.25 16:36:10 INFO - PROCESS | 2235 | Values: 87.0 70.0 87.0 73.0 84.0 75.0 86.0 69.0 70.0 89.0 16:36:10 INFO - PROCESS | 2235 | 16:36:10 INFO - PROCESS | 2235 | [#5] imaging-gaussian-blur Cycles:10 Average:103.00 Median:88.50 stddev:21.97 (24.8%) stddev-sans-first:22.87 16:36:10 INFO - PROCESS | 2235 | Values: 91.0 88.0 91.0 89.0 100.0 89.0 137.0 131.0 80.0 134.0 16:36:10 INFO - PROCESS | 2235 | 16:36:10 INFO - PROCESS | 2235 | [#6] imaging-darkroom Cycles:10 Average:104.00 Median:96.00 stddev:19.28 (20.1%) stddev-sans-first:20.23 16:36:10 INFO - PROCESS | 2235 | Values: 96.0 96.0 95.0 96.0 94.0 153.0 123.0 95.0 96.0 96.0 16:36:10 INFO - PROCESS | 2235 | 16:36:10 INFO - PROCESS | 2235 | [#7] imaging-desaturate Cycles:10 Average:105.20 Median:83.00 stddev:21.57 (26.0%) stddev-sans-first:21.96 16:36:10 INFO - PROCESS | 2235 | Values: 88.0 110.0 111.0 86.0 91.0 92.0 115.0 141.0 80.0 138.0 16:36:10 INFO - PROCESS | 2235 | 16:36:10 INFO - PROCESS | 2235 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:58.00 stddev:1.17 (2.0%) stddev-sans-first:1.22 16:36:10 INFO - PROCESS | 2235 | Values: 57.0 56.0 57.0 58.0 57.0 59.0 59.0 58.0 59.0 56.0 16:36:10 INFO - PROCESS | 2235 | 16:36:10 INFO - PROCESS | 2235 | [#9] json-stringify-tinderbox Cycles:10 Average:78.50 Median:78.00 stddev:10.67 (13.7%) stddev-sans-first:10.97 16:36:10 INFO - PROCESS | 2235 | Values: 71.0 78.0 70.0 98.0 94.0 78.0 70.0 69.0 86.0 71.0 16:36:10 INFO - PROCESS | 2235 | 16:36:10 INFO - PROCESS | 2235 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:89.00 stddev:9.83 (11.0%) stddev-sans-first:2.99 16:36:10 INFO - PROCESS | 2235 | Values: 119.0 88.0 91.0 88.0 87.0 85.0 87.0 93.0 90.0 94.0 16:36:10 INFO - PROCESS | 2235 | 16:36:10 INFO - PROCESS | 2235 | [#11] stanford-crypto-ccm Cycles:10 Average:166.00 Median:156.50 stddev:27.85 (17.8%) stddev-sans-first:29.39 16:36:10 INFO - PROCESS | 2235 | Values: 158.0 155.0 146.0 148.0 148.0 148.0 149.0 184.0 229.0 195.0 16:36:10 INFO - PROCESS | 2235 | 16:36:10 INFO - PROCESS | 2235 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.70 Median:178.00 stddev:21.60 (12.1%) stddev-sans-first:22.78 16:36:10 INFO - PROCESS | 2235 | Values: 177.0 178.0 178.0 176.0 176.0 178.0 177.0 179.0 173.0 245.0 16:36:10 INFO - PROCESS | 2235 | 16:36:10 INFO - PROCESS | 2235 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:69.00 stddev:1.42 (2.1%) stddev-sans-first:1.48 16:36:10 INFO - PROCESS | 2235 | Values: 69.0 69.0 68.0 68.0 71.0 69.0 67.0 69.0 67.0 66.0 16:36:10 INFO - PROCESS | 2235 | -------- Summary: end -------- 16:36:10 INFO - PROCESS | 2235 | 16:36:10 INFO - TEST-INFO | 2235: exit 0 16:36:10 INFO - TEST-OK | kraken | took 61218ms 16:36:10 INFO - SUITE-END | took 1178s 16:36:11 INFO - Completed test suite (00:19:38) 16:36:11 INFO - javascript benchmark 16:36:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7106.51103597047, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2812.915612531625, 3328.7669863109586, 3337.149026998117, 3356.110630682868, 3381.273026299346, 5751.798687045949, 5803.535717142629, 5813.128030795383, 5845.853853653661, 5887.8233652990375, 5642.7178641067885, 5744.023516002279, 5746.1668058131545, 5796.884062318751, 5806.042003069489, 5245.186996015615, 5274.340707411574, 5288.947110528884, 5320.866978325535, 5362.195670649395, 5327.627066105355, 5348.946510534904, 5357.785688572452, 5369.167778994242, 5375.946240537603, 5254.395744489375, 5342.973285133569, 5377.892442151155, 5381.973090134564, 5398.973005134969, 5321.787128514855, 5379.81170659027, 5393.865153371158, 5395.379531353905, 5395.73021348931, 4171.874843754692, 5241.266222728821, 5261.710880834182, 5262.342207224097, 5335.33308336458, 5031.597472202252, 5621.437856214387, 5844.386339434341, 5982.521398288127, 6029.306629737593, 5540.279763630702, 6214.067889816519, 6241.34465881085, 6269.122322874801, 6303.086052522359, 5323.920141197885, 6157.353477884847, 6277.152584401093, 6277.435030847246, 6299.68501574924, 5905.586608937376, 5930.080837470199, 5950.851228719273, 5968.22413086296, 5990.97004514979, 5197.818076367309, 6178.814635560941, 6216.595921265103, 6254.781082662176, 6269.279032911182, 6221.471174950089, 6229.719662615194, 6233.158523599347, 6257.342978987232, 6273.749050038039, 5513.338399392019, 6236.158118654014, 6291.370862913672, 6298.401651843067, 6324.146240257598, 4813.638977076734, 5421.620486565903, 5780.277465316835, 6137.938620613737, 6331.873362532723, 6127.969360153171, 6144.863200307957, 6160.599561028519, 6202.472789812917, 6280.874382512324, 3998.8600398985895, 5005.424376196711, 5005.974970125126, 5032.220005899091, 5033.245013247969, 4453.22068637987, 4463.709858859163, 4493.4607847057905, 4498.167838949805, 4522.412086428743, 5833.241678581842, 5894.440028197314, 5915.645061296336, 5978.222831031938, 6025.096235564701, 6168.321484636686, 6264.9976003839165, 6293.244810622755, 6297.685115744194, 6302.590331628429, 4836.371271734652, 5834.416558344216, 6005.129256257818, 6015.969920150371, 6130.969345153246, 5444.8094316698725, 5465.180222966586, 5504.724763761876, 5509.3664228614225, 5572.860678482989, 6185.072239164071, 6257.342978987141, 6264.780732674334, 6281.434670879643, 6346.524010699309, 6143.634700640197, 6259.499240060693, 6288.874222515616, 6294.118823364641, 6294.874102518015, 6400.039994000937, 6400.359964003563, 6412.935870641234, 6425.710843011982, 6442.613443193423, 6125.816225513242, 6145.617236121837, 6227.221597300338, 6240.157578726814, 6261.843453913506, 6193.194884664965, 6207.193064901534, 6237.96881015592, 6240.469560087444, 6260.718267677874, 6168.0439531872635, 6173.135760993554, 6221.4089661482085, 6241.500679945684, 6248.094026366242, 5930.703464826655, 5945.019071853096, 6179.887620228402, 6197.81406557786, 6230.345813689497, 5860.912086318794, 5959.612625179349, 5975.641461512323, 5981.880362392641, 6059.515238780798, 6114.114890844268, 6126.914660831567, 6128.655903792168, 6146.555559443616, 6162.075688646739, 6099.84750381244, 6105.186826067751, 6106.339326077157, 6109.556495504338, 6113.689152827286], "value": 5714.874372020276, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.5492080459139, 451.6522277846059, 456.0377603257129, 478.95449932256435, 518.1321286844534, 36667.71662991796, 38606.61393386065, 38687.22625547491, 38793.80603096988, 38907.416388754194, 35491.112722181955, 37406.62593374065, 38602.03494912628, 39184.0, 39323.0, 8316.708915187985, 9333.300002499805, 9340.346175767665, 9342.766430839249, 9361.438313701166, 9239.815203695956, 9285.071492850695, 9355.111264429868, 9356.251499880027, 9381.577828997677, 9341.766455838624, 9372.8594071089, 9385.95307023464, 9391.107844754737, 9411.95294023529, 9276.11876871696, 9278.907210927871, 9323.0, 9341.486218257993, 9395.765105872408, 9104.817903641957, 9113.544322783835, 9139.634414623408, 9226.723198303995, 9229.538523073887, 38010.80994595037, 38044.61955380438, 38239.0, 38262.80868595639, 38336.61663383358, 3118.7660925430455, 3177.2215807127172, 3240.189952511872, 3319.452290372086, 3397.2356219850585, 28600.570991435146, 29081.272968175752, 29163.125106246847, 29289.70710292891, 29315.560266596018, 3156.1478400831875, 3181.1887968567867, 3206.4549026665522, 3276.639569647337, 3286.917827054319, 474720.0, 482936.0, 482973.9999999965, 483063.0, 483085.0, 46321.073578528914, 55815.69750873661, 56035.43964560384, 56134.15798763022, 56334.0, 58632.7068364664, 59304.40695592989, 59400.0, 59504.0, 59518.0, 15104.924475377553, 15280.541583752505, 15310.081395116329, 15366.923165384102, 15388.692226155414, 11750.647480575597, 11821.940890295493, 11865.762684746256, 11901.583444579572, 11914.404279785977, 15089.698206035819, 15674.764878526832, 15740.370385184693, 15746.842531574537, 15781.28984195714, 6359.728054389141, 6629.303923088103, 6710.597364158166, 6723.764668236686, 6754.729810807611, 811.8701007838717, 834.5034704351023, 973.6397532912725, 1004.2417974429259, 1065.9946700266448, 28937.710622893923, 28939.710602894123, 29194.562081568794, 29461.0, 29932.850335748182, 935.3452583191657, 951.9952400237954, 1012.3318609717551, 1023.2683631203577, 1032.7934413117407, 14586.0, 15139.772903406458, 15147.166905820286, 15388.461403850812, 15469.922650386676, 601209.0, 606143.0, 610026.0, 612408.0, 614142.0, 55017.1747423789, 55451.0, 55480.44519554834, 55570.44429555733, 55702.0, 1091.9945400272948, 1303.3157592264138, 1329.3419757220238, 1338.4980632262902, 1362.0805955979674, 27992.020279290125, 28779.28051798722, 29044.75364804306, 29174.0, 29393.0, 45653.771731142464, 48176.75911620419, 48978.75510622424, 49096.50903490919, 49255.50744492509, 603642.0, 606138.9079163905, 606861.0, 608194.0, 608385.0, 25295.0, 25563.744362556135, 25641.61537576975, 25665.61501577515, 25691.486170276115, 43505.0, 43530.34704479499, 44256.11487770162, 44649.33026004548, 45073.54926450825, 32630.34739305248, 36992.26015479622, 37577.4363384544, 37642.741435513955, 38037.80981095077, 36430.4535431974, 36437.45343819898, 37348.43977340397, 37726.43410348792, 37762.43356349712, 29047.273818154714, 35514.467282990256, 36456.17719113981, 36574.085647859014, 36639.816800915825, 26280.73719262783, 34053.31893362168, 34903.30193396169, 35023.47464787979, 35197.82401087978, 22326.77673223247, 23157.536849262582, 23621.881890590437, 24734.876325618257, 25356.492870142123], "value": 18963.676199809834, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.139622792057, 358.7050746804037, 364.7847769815808, 372.24805892097953, 375.073568286333, 18242.452726418196, 18904.716429253556, 19487.0, 19929.800701992994, 20209.292674756387, 19394.22423103074, 19451.90274048631, 20003.599928001433, 20050.49873753157, 20087.09608067637, 5615.80344687936, 5814.447627475404, 5901.321348044966, 5902.055671092626, 5902.2622172228475, 5354.785808567653, 5647.717614119299, 5708.257926469553, 5762.74067666956, 5770.0, 5644.633098848582, 5734.13987901816, 5749.195112684228, 5751.367349591542, 5759.740811663485, 5988.311344195408, 6031.0, 6069.089636554508, 6077.726502307407, 6089.299730530979, 5470.17947307903, 5545.556355491592, 5634.239377684001, 5729.713514324267, 5857.355690874, 23579.646305305436, 23773.524529509312, 24181.274561763174, 24362.0, 24748.876255618787, 2006.3078238007863, 2050.866693664907, 2133.797289257518, 2261.7625149359246, 2421.2615152378517, 9905.653302134391, 10113.797724045478, 10221.591136354537, 10265.692029239135, 11992.580259690956, 1790.5613124784381, 1852.5924296654714, 1854.3695143651223, 1873.9812601873944, 1902.286642509059, 116902.0, 119455.40272298583, 119561.0, 119826.0, 120839.39580302042, 9070.81858362829, 11091.44542772858, 11148.219624626361, 11462.254953428, 11548.711282217844, 13842.93078534621, 14066.929665351608, 14067.577972660632, 14068.929655351658, 14090.365933533016, 6797.2523022467485, 8181.222783835526, 8199.7950051248, 8292.792680182924, 8297.336213103057, 8027.15714849944, 8057.677692892219, 8064.717734879251, 8234.876476852853, 8317.792055198548, 4297.383745590134, 4410.184115938498, 4430.56006797792, 4503.932441013388, 4508.752018639007, 1847.3718935561974, 1907.9523011924534, 1912.8660993730584, 1914.9712754308696, 1915.961680766377, 771.0824119298129, 801.3949468151508, 850.1795767084793, 893.2942975049768, 931.8462453695064, 18838.246470141316, 19552.0, 20037.899810500854, 20525.0, 20587.795616946143, 876.8202518483696, 897.2552781191596, 905.7282815155427, 918.3617385916724, 919.6689191890914, 8857.822843543221, 10442.68671939843, 10728.409937453358, 10756.354618722902, 10781.676549703521, 483820.0, 489539.5523022362, 491267.0, 593961.0, 596517.0, 119038.80961190451, 125610.0, 128575.35712321565, 129508.0, 131438.0, 997.7006897930737, 1002.9849552256723, 1060.4591658254192, 1066.4134725900724, 1068.7221322456217, 10665.89334106649, 11413.885861141614, 15737.504937030964, 16245.59386015359, 18159.546011349823, 45383.773081134386, 54893.17660235179, 55130.44869551253, 55681.44318556762, 56419.0, 77425.61287193529, 82208.17791822006, 82536.0, 83925.58037209774, 84762.1523784779, 11723.41382930867, 12097.395130243629, 12156.392180391122, 12182.939085304872, 12612.684682883002, 7201.855962880818, 11636.825447618461, 13030.934845325713, 13477.506661267696, 13596.728065438834, 11089.445527723743, 11392.031677307521, 11691.181617286707, 11693.532258709556, 11995.520179193083, 11502.88497115018, 11594.478248479007, 11658.88341116612, 11761.823572646246, 12077.27536347822, 7649.273319034683, 7878.921210787818, 8200.138985406686, 8280.958595206985, 9340.299477539076, 12057.03543716483, 12106.394680266128, 12183.512659493521, 12425.751484970431, 12550.811737824122, 9236.676716314896, 9892.971940752272, 11510.48202830858, 11701.531938722355, 11849.703757406134], "value": 9459.708248560139, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1183.1717797541721, 1384.5984664447308, 1572.3710515793682, 1736.4964160393486, 1818.5635447492598, 7063.752768653098, 7608.657610407531, 7910.604469776503, 8629.223369896708, 8661.78345541361, 8073.596320183997, 8406.91593084069, 8444.704435344764, 8535.146485351477, 8538.06081331053, 2259.3899647095272, 2288.1533832482005, 2299.1608063056947, 2300.3559003479054, 2304.4238940264936, 2274.9772502275014, 2316.0388438797963, 2333.9066437342485, 2335.1476711000446, 2345.8592484450987, 2268.9092436302526, 2277.237125562938, 2303.5623231585946, 2307.4462129088984, 2316.6177580699164, 2268.1834539565743, 2268.274152271274, 2285.0631241191113, 2290.1068583252545, 2295.9553403201585, 2286.9708631116064, 2308.2728940383895, 2308.6883270758567, 2319.22306027481, 2321.7097862767155, 8387.119352467964, 8424.157584241588, 8481.703140390118, 8486.363522735819, 8497.192766687154, 5032.874178145576, 5187.144121220032, 5192.948070519285, 5197.584193264567, 5205.427402985669, 3178.995848922769, 3240.659730728263, 3521.066917266901, 3527.4708793680898, 3534.452159915217, 1005.2661557063385, 1025.887152413234, 1041.8541404203418, 1057.740853490892, 1061.4321338084087, 11010.779784404347, 11028.44857757109, 11059.447027648584, 11127.33236005834, 11145.498452569655, 4444.4888837784065, 4526.932096018562, 4593.678442509059, 4608.654350923694, 4686.531346865354, 9455.621775128919, 9522.428654280766, 9536.046395360547, 9541.47521886303, 9562.28282878787, 5215.217717342429, 5219.399769026534, 5221.895562088737, 5231.372235331784, 5234.790608375699, 4287.3140297552245, 4300.4839419269265, 4720.693154944917, 4766.499517550607, 4775.4269487661, 3264.9347013059605, 3726.5341832270965, 3774.1319496515957, 3811.847526098925, 3852.8266228019806, 1268.689171153055, 1276.8212450256972, 1278.8337516122845, 1290.1291628151148, 1290.6321698315935, 805.5287656720767, 875.6978842299396, 894.6197865906963, 920.5719340506696, 931.0940850762436, 5510.91733623996, 5669.914951275734, 5783.88432231353, 5846.941530584725, 5868.119782032644, 904.5884122724144, 924.3483344242293, 936.990630093704, 937.8417654197061, 977.9266555008403, 3821.38857782759, 3839.8080095995647, 3846.519185101862, 3957.6438120569287, 3967.2858885400906, 7979.3217576505485, 10268.589256429808, 10293.176545876311, 10296.845547316796, 10331.121854642439, 8609.397342185986, 10043.995600439897, 10060.849087263698, 10246.743831404125, 10280.794384112425, 7488.812779680443, 7795.493292935941, 7851.960740196262, 7863.921360786433, 7887.329576985906, 5027.74861256943, 5745.425457454221, 5798.623089499169, 5856.531477481876, 5948.078047902582, 9015.774605634911, 9022.458652480609, 9359.39163954368, 9467.621295148117, 9479.952600237231, 8698.521581312836, 9383.73319601845, 9432.669856554987, 9645.131938125602, 9691.224702023963, 6660.200775906922, 6883.414909732729, 7039.049728286821, 7122.109736282965, 7263.818404539928, 7963.880541791762, 8118.18818118207, 8141.674333026613, 8156.551389673387, 8186.631601578062, 7012.1935977363255, 7136.286371362822, 7247.927520724726, 7317.304856038668, 7361.742339018108, 6903.206131294965, 7109.28907109285, 7110.7511237106455, 7205.459590530836, 7218.556288742167, 6708.6310252936555, 6737.494687898526, 6805.081314022544, 6806.489513286622, 6812.14848143982, 4528.977355113203, 4838.322634831196, 4901.754912254444, 4921.2864134700985, 4933.777979990838, 4790.305405716222, 4875.414950205998, 4894.898647804215, 4918.729469879191, 4985.900281994267], "value": 4424.218804648635, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [255.27756449248628, 298.53130584981574, 306.0954878334522, 306.2023428967538, 310.3002728846451, 4572.451305843299, 4584.195118052017, 4849.247737217524, 5454.354638000566, 5573.708244651621, 5256.26412302278, 5431.891362172754, 5452.972735136329, 5476.698781567012, 5556.611037227396, 3556.982215088934, 4179.289520781459, 4206.810693518792, 4232.49210094789, 4239.173361194568, 3752.2683076800035, 3847.9615203847884, 4251.787410629471, 4252.9792849716, 4258.444933040182, 3896.8504291234153, 3922.6270805218232, 4048.75707457552, 4113.305784711019, 4151.422459465399, 3009.653889802679, 3216.4371235033777, 3487.9361794652623, 3639.5632524097014, 4030.7504673551293, 2914.111196085193, 3069.3247485553143, 3120.92328146789, 3564.7682900611635, 3584.22939068099, 4162.979185104085, 5152.871178220537, 5202.557782588485, 5246.081935661244, 5436.933965093239, 4088.8160032798596, 4639.930401043987, 4648.279516674921, 4669.6497762667595, 4741.95258047422, 4829.830955916526, 4969.757560609847, 5716.799912003102, 5720.256366672306, 5770.855728606818, 3582.731295152874, 3800.809959502014, 3868.903277418059, 3885.7668539887413, 3895.220955808821, 5768.913466298009, 5971.850703732442, 5972.402005127783, 6094.756209751649, 6120.693965301762, 3897.824597893102, 3900.473436086149, 3901.785401802928, 3914.393269043303, 3927.7446965947124, 3526.823658817049, 3806.2958352704836, 3919.5884432134217, 3952.26883026641, 3975.344068228768, 4592.747398893093, 4613.308003799457, 4614.953850461519, 4671.5328467153695, 4702.717836929795, 5791.7683292667825, 5808.448197421239, 5847.6783776891825, 5855.941440585625, 5885.47030767233, 1115.7210697325668, 1206.288289908958, 1214.3017764785284, 1249.6938250128776, 1274.6112435707198, 840.0129847429246, 859.9011113721876, 865.7402779166225, 866.328595338605, 869.5695630662736, 4373.272557339833, 5084.161113416245, 5101.566366858784, 5125.666831655931, 5132.435432102466, 5325.680459172462, 5503.752331145108, 5540.337898630395, 5588.2455868458055, 5621.0, 4284.228838808982, 4885.731284779371, 4954.8513544593, 5008.524190201925, 5102.464241254689, 2455.72986971433, 2954.335274563206, 2961.9703802962126, 2975.0101148345775, 3005.9549106763416, 5247.475252474722, 5673.687947162945, 5777.451142141574, 5943.970280148658, 5957.731902064417, 4630.6063984561015, 5399.595030372738, 5411.485908838733, 5891.46976772084, 6211.223597050369, 5292.523672869537, 5933.673647949404, 6292.143817573827, 6292.590981586182, 6303.982725527787, 5318.97340513295, 5972.432618901197, 6034.9094763577705, 6063.423974722395, 6122.7244773984385, 5714.685692286964, 5864.50758427658, 5871.354151043467, 5919.23050003514, 5929.792457263975, 5803.9419605803405, 6102.511799055976, 6186.566940314135, 6251.530890240881, 6344.809655710336, 3723.3670276052594, 3867.5939026401825, 3869.9234108882943, 3909.941350879796, 3916.942425545144, 5026.894083301668, 5327.174287985368, 5943.970280148572, 6042.546808989431, 6052.939470605414, 5601.80393686219, 5605.757696921264, 5788.176724331822, 5834.38738932423, 5863.442972917566, 4803.831865884677, 4991.975040124777, 5299.6290259681455, 5858.7070646468355, 5892.705364731661, 4861.440934292602, 5442.727863606883, 5611.438856114356, 5697.8319444512945, 5741.483266506035, 5312.150055991023, 5793.205450429975, 5816.272965879265, 5827.834433113309, 5869.735861886311, 5189.974050129877, 5632.042552766122, 5670.631408958405, 5705.914411283917, 5736.71316434185], "value": 4116.220888681881, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.1325045557801, 655.7737580534715, 704.40125892991, 740.3484933258728, 801.3989507869098, 13734.519291824789, 14407.783883241747, 14605.269736513184, 14650.560483185494, 14666.486672966448, 12980.610581682564, 13456.66358341042, 13576.524821631245, 13578.660533486667, 13584.864151358483, 723.4284914917235, 824.5382585751968, 834.9499030058186, 845.750503601436, 863.8876945997011, 662.6189940784044, 680.8706345794308, 681.870444615524, 842.2293601354753, 846.9534175620338, 871.9782005449882, 884.4914174349742, 885.0529932971723, 885.0706757904208, 886.3706768194589, 876.2858270509546, 884.1247165306364, 884.9867251991225, 886.0032463478586, 886.8226354729047, 649.3409189672486, 652.8008957268031, 656.5076192855358, 656.9638669873156, 658.6673729766485, 12931.935340323238, 13032.869671303355, 13069.15050521713, 13106.868931310755, 13724.794128088086, 11443.828342574869, 13093.952483801273, 13319.73360532784, 13839.446422143103, 13947.721045579032, 9859.704208873745, 11360.772784544346, 11427.142964277713, 11496.0, 11699.883001169965, 3590.7666001709804, 3661.9450708239397, 3773.8301776420126, 3800.657940785343, 3812.9809350953346, 69288.0, 88795.0, 91477.0, 93722.0, 93860.0613993865, 9364.391314564531, 10553.261271710906, 10635.680929572125, 10739.301945373525, 10866.130709543218, 21117.26089586857, 21170.0, 21218.681719774217, 21291.36125916225, 21336.573268534543, 7078.0, 7276.745313913988, 7402.1117465904435, 7493.775186744407, 7614.390848732088, 4125.525564560114, 4180.184863951561, 4237.322028475429, 4239.40648309237, 4330.545292744279, 3151.5115157150676, 3211.0527394418705, 3245.902622921316, 3246.4967929970894, 3252.10567094051, 510.2677657561337, 539.156220514893, 607.2561112636985, 609.0894113300644, 621.0311913414997, 490.5879061588283, 590.2680675961776, 599.3526990849872, 600.0179109824167, 622.1973653986397, 8169.714060007871, 8312.5428101455, 8409.579521023925, 8565.143485651384, 8706.390552661253, 508.5194491205849, 515.3223511082914, 562.361719448429, 617.4227097663698, 618.0821480102045, 3985.262726395626, 4133.23535145999, 4186.665066794649, 4187.392828039978, 4210.179015092089, 82251.0, 104099.91800163954, 113834.43082784532, 115335.2699709505, 115821.42089289668, 100763.0, 105598.47200763947, 105696.47151764193, 106660.0, 107069.0, 82949.0, 83435.74846377187, 83500.74748878772, 85839.0, 87149.56425217833, 7917.2874441300555, 7996.600169991593, 8059.028939967004, 8177.877331840146, 8226.341892648454, 42222.99999999877, 45950.39173628907, 48987.59111679966, 54645.0, 55208.791648335224, 58403.0, 74524.50950980841, 79311.60344198473, 80034.0, 81587.18412815795, 9875.40747555149, 10060.547275372479, 10162.542685579314, 10178.0, 10281.074703276741, 9514.571844266886, 10461.843072353768, 11029.062529685067, 11202.551897924317, 11438.714032149262, 7897.123155261935, 8893.777655558662, 9903.702888913343, 10020.29464449639, 10875.129989600655, 10515.579376825148, 10692.62575809874, 10862.402567858528, 10992.505337259683, 11108.555657773923, 10644.0, 11054.0, 12229.87770122323, 12657.303848288433, 12813.103082784115, 10480.842787358348, 10587.894121058998, 10644.361338319726, 10737.946310268399, 11216.943915280372, 7594.582297973665, 9053.818923621622, 10828.945855270673, 11103.056240219348, 11154.442277886235], "value": 6898.45176340092, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1605.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 119.0, 160.0, 112.0, 103.0, 131.0, 173.0, 177.0, 120.0, 120.0], "value": 132.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 147.0, 145.0, 156.0, 118.0, 104.0, 135.0, 141.0, 132.0, 154.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 207.0, 278.0, 269.0, 260.0, 195.0, 193.0, 199.0, 195.0, 247.0], "value": 224.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 60.0, 85.0, 60.0, 59.0, 60.0, 62.0, 108.0, 80.0], "value": 71.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 87.0, 73.0, 84.0, 75.0, 86.0, 69.0, 70.0, 89.0], "value": 79.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 91.0, 89.0, 100.0, 89.0, 137.0, 131.0, 80.0, 134.0], "value": 103.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 96.0, 94.0, 153.0, 123.0, 95.0, 96.0, 96.0], "value": 104.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 110.0, 111.0, 86.0, 91.0, 92.0, 115.0, 141.0, 80.0, 138.0], "value": 105.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 58.0, 57.0, 59.0, 59.0, 58.0, 59.0, 56.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 78.0, 70.0, 98.0, 94.0, 78.0, 70.0, 69.0, 86.0, 71.0], "value": 78.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 88.0, 91.0, 88.0, 87.0, 85.0, 87.0, 93.0, 90.0, 94.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 155.0, 146.0, 148.0, 148.0, 148.0, 149.0, 184.0, 229.0, 195.0], "value": 166.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 178.0, 178.0, 176.0, 176.0, 178.0, 177.0, 179.0, 173.0, 245.0], "value": 183.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 68.0, 71.0, 69.0, 67.0, 69.0, 67.0, 66.0], "value": 68.3, "unit": "score"}]}]} 16:36:11 INFO - Return code: 0 16:36:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:36:11 ERROR - # TBPL SUCCESS # 16:36:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:36:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:36:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:36:11 INFO - 2016-11-11 16:36:11.333 screenresolution[2240:29014] starting screenresolution argv=screenresolution get 16:36:11 INFO - 2016-11-11 16:36:11.335 screenresolution[2240:29014] Display 0: 1600x1200x32@60 16:36:11 INFO - 2016-11-11 16:36:11.342 screenresolution[2241:29017] starting screenresolution argv=screenresolution list 16:36:11 INFO - Available Modes on Display 0 16:36:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:36:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:36:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:36:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:36:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:36:11 INFO - Intel Iris: 16:36:11 INFO - Chipset Model: Intel Iris 16:36:11 INFO - Type: GPU 16:36:11 INFO - Bus: Built-In 16:36:11 INFO - VRAM (Dynamic, Max): 1536 MB 16:36:11 INFO - Vendor: Intel (0x8086) 16:36:11 INFO - Device ID: 0x0a2e 16:36:11 INFO - Revision ID: 0x0009 16:36:11 INFO - Displays: 16:36:11 INFO - Display: 16:36:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:36:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:36:11 INFO - Display Serial Number: 1600x1200 60 16:36:11 INFO - Main Display: Yes 16:36:11 INFO - Mirror: Off 16:36:11 INFO - Online: Yes 16:36:11 INFO - Rotation: Supported 16:36:11 INFO - Return code: 0 16:36:11 INFO - Running post-action listener: _resource_record_post_action 16:36:11 INFO - [mozharness: 2016-11-12 00:36:11.588965Z] Finished run-tests step (success) 16:36:11 INFO - Running post-run listener: _resource_record_post_run 16:36:11 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 270336; Write bytes: 702533632; Read time: 3; Write time: 2083 16:36:11 INFO - TinderboxPrint: CPU usage
54.5% 16:36:11 INFO - TinderboxPrint: I/O read bytes / time
270,336 / 3 16:36:11 INFO - TinderboxPrint: I/O write bytes / time
702,533,632 / 2,083 16:36:11 INFO - TinderboxPrint: CPU idle
2,208.1 (45.5%) 16:36:11 INFO - TinderboxPrint: CPU system
985.0 (20.3%) 16:36:11 INFO - TinderboxPrint: CPU user
1,660.5 (34.2%) 16:36:11 INFO - TinderboxPrint: Swap in / out
398,348,288 / 0 16:36:11 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 394093056; Write bytes: 388167168; Read time: 29558; Write time: 825 16:36:11 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 270336; Write bytes: 300173824; Read time: 3; Write time: 1211 16:36:12 INFO - Running post-run listener: _upload_blobber_files 16:36:12 INFO - Blob upload gear active. 16:36:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:36:12 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 16:36:12 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'] 16:36:12 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 16:36:12 INFO - (blobuploader) - INFO - Open directory for files ... 16:36:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:36:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:36:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:13 INFO - (blobuploader) - INFO - Done attempting. 16:36:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:36:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:36:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:14 INFO - (blobuploader) - INFO - Done attempting. 16:36:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:36:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:36:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:17 INFO - (blobuploader) - INFO - Done attempting. 16:36:17 INFO - (blobuploader) - INFO - Iteration through files over. 16:36:17 INFO - Return code: 0 16:36:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:36:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:36:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cf5eee977e69bcc12fb79309166a3c81619146c7625b53d29c80f39555729c06591191f37c766d962675750bba4c4e984e37e08dd10088abf7a2c84a87339e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e0934ef51e390f97089350a7f0a0ceccf89d573e9bdbf6cafed55c3a018d9c0abce2aa1fa3878ce84ac6bea0e467a66e75ca31f6dd506c1e27ff3c5c6490fdd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bca6b12ba59ed6d11fdaa93b9cd55619c1cf50f3c81cf8b061861385990b3a4b1da3599ca17d56bd775e51c69aea64ebe1d1a6bb0515a9fe33664c9f4e4984f8"} 16:36:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:36:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:36:17 INFO - Contents: 16:36:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cf5eee977e69bcc12fb79309166a3c81619146c7625b53d29c80f39555729c06591191f37c766d962675750bba4c4e984e37e08dd10088abf7a2c84a87339e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e0934ef51e390f97089350a7f0a0ceccf89d573e9bdbf6cafed55c3a018d9c0abce2aa1fa3878ce84ac6bea0e467a66e75ca31f6dd506c1e27ff3c5c6490fdd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bca6b12ba59ed6d11fdaa93b9cd55619c1cf50f3c81cf8b061861385990b3a4b1da3599ca17d56bd775e51c69aea64ebe1d1a6bb0515a9fe33664c9f4e4984f8"} 16:36:17 INFO - Running post-run listener: copy_logs_to_upload_dir 16:36:17 INFO - Copying logs to upload dir... 16:36:17 INFO - mkdir: /builds/slave/test/build/upload/logs 16:36:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.329462 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-11 16:36:17.179527) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-11 16:36:17.182525) ========= 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.4jOdIncKwl/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.5XkrtxD1Qb/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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/8cf5eee977e69bcc12fb79309166a3c81619146c7625b53d29c80f39555729c06591191f37c766d962675750bba4c4e984e37e08dd10088abf7a2c84a87339e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e0934ef51e390f97089350a7f0a0ceccf89d573e9bdbf6cafed55c3a018d9c0abce2aa1fa3878ce84ac6bea0e467a66e75ca31f6dd506c1e27ff3c5c6490fdd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bca6b12ba59ed6d11fdaa93b9cd55619c1cf50f3c81cf8b061861385990b3a4b1da3599ca17d56bd775e51c69aea64ebe1d1a6bb0515a9fe33664c9f4e4984f8"} build_url:https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011010 build_url: 'https://queue.taskcluster.net/v1/task/fGE0N7YuSOqKs6wbTo9c1w/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/8cf5eee977e69bcc12fb79309166a3c81619146c7625b53d29c80f39555729c06591191f37c766d962675750bba4c4e984e37e08dd10088abf7a2c84a87339e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e0934ef51e390f97089350a7f0a0ceccf89d573e9bdbf6cafed55c3a018d9c0abce2aa1fa3878ce84ac6bea0e467a66e75ca31f6dd506c1e27ff3c5c6490fdd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bca6b12ba59ed6d11fdaa93b9cd55619c1cf50f3c81cf8b061861385990b3a4b1da3599ca17d56bd775e51c69aea64ebe1d1a6bb0515a9fe33664c9f4e4984f8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-11 16:36:17.217173) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 16:36:17.217476) ========= 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.4jOdIncKwl/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.5XkrtxD1Qb/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.005187 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 16:36:17.265944) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-11 16:36:17.269271) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-11 16:36:17.269609) ========= ========= Total master_lag: 0.21 =========