builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0006 starttime: 1479125081.17 results: success (0) buildid: 20161114012320 builduid: 402628d536814718bdbdf641ed2ea43a revision: 1196bf3032e1bce1fb07a01fd9082a767426c5fb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 04:04:41.165814) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 04:04:41.166262) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 04:04:41.166534) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 04:04:41.191420) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 04:04:41.191723) ========= 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.MzRdiwLXWq/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.Ne7TrzGqhs/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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-14 04:04:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 153M=0s 2016-11-14 04:04:41 (153 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140825 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 04:04:41.355162) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 04:04:41.355472) ========= 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.MzRdiwLXWq/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.Ne7TrzGqhs/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.025957 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 04:04:41.403558) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-11-14 04:04:41.403890) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1196bf3032e1bce1fb07a01fd9082a767426c5fb --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 1196bf3032e1bce1fb07a01fd9082a767426c5fb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MzRdiwLXWq/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.Ne7TrzGqhs/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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-14 04:04:41,481 truncating revision to first 12 chars 2016-11-14 04:04:41,481 Setting DEBUG logging. 2016-11-14 04:04:41,481 attempt 1/10 2016-11-14 04:04:41,481 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1196bf3032e1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 04:04:42,516 unpacking tar archive at: mozilla-central-1196bf3032e1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.288865 ========= master_lag: 1.96 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-11-14 04:04:44.652646) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 04:04:44.652985) ========= script_repo_revision: 1196bf3032e1bce1fb07a01fd9082a767426c5fb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 04:04:44.653398) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 3 secs) (at 2016-11-14 04:04:44.653665) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 3 secs) (at 2016-11-14 04:04:48.105284) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-14 04:04:48.105605) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.MzRdiwLXWq/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.Ne7TrzGqhs/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 04:04:48 INFO - MultiFileLogger online at 20161114 04:04:48 in /builds/slave/test 04:04:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:04:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:04:48 INFO - {'append_to_log': False, 04:04:48 INFO - 'base_work_dir': '/builds/slave/test', 04:04:48 INFO - 'blob_upload_branch': 'Firefox', 04:04:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:04:48 INFO - 'branch': 'Firefox', 04:04:48 INFO - 'buildbot_json_path': 'buildprops.json', 04:04:48 INFO - 'config_files': ('talos/mac_config.py',), 04:04:48 INFO - 'default_actions': ('clobber', 04:04:48 INFO - 'read-buildbot-config', 04:04:48 INFO - 'download-and-extract', 04:04:48 INFO - 'populate-webroot', 04:04:48 INFO - 'create-virtualenv', 04:04:48 INFO - 'install', 04:04:48 INFO - 'run-tests'), 04:04:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:04:48 INFO - 'download_minidump_stackwalk': True, 04:04:48 INFO - 'download_symbols': 'ondemand', 04:04:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:04:48 INFO - 'tooltool.py': '/tools/tooltool.py', 04:04:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:04:48 INFO - '/tools/misc-python/virtualenv.py')}, 04:04:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:04:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:04:48 INFO - 'installer_path': 'installer.exe', 04:04:48 INFO - 'log_level': 'info', 04:04:48 INFO - 'log_name': 'talos', 04:04:48 INFO - 'log_to_console': True, 04:04:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:04:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:04:48 INFO - 'opt_config_files': (), 04:04:48 INFO - 'pip_index': False, 04:04:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:04:48 INFO - 'cmd': ('bash', 04:04:48 INFO - '-c', 04:04:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:04:48 INFO - 'enabled': True, 04:04:48 INFO - 'halt_on_failure': False, 04:04:48 INFO - 'name': 'check_screen_resolution'},), 04:04:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:04:48 INFO - 'cmd': ('bash', 04:04:48 INFO - '-c', 04:04:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:04:48 INFO - 'enabled': True, 04:04:48 INFO - 'halt_on_failure': False, 04:04:48 INFO - 'name': 'check_screen_resolution'},), 04:04:48 INFO - 'run_cmd_checks_enabled': True, 04:04:48 INFO - 'sps_profile': False, 04:04:48 INFO - 'sps_profile_interval': 0, 04:04:48 INFO - 'suite': 'dromaeojs', 04:04:48 INFO - 'system_bits': '32', 04:04:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:04:48 INFO - 'title': 't-yosemite-r7-0006', 04:04:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:04:48 INFO - 'use_talos_json': True, 04:04:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:04:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:04:48 INFO - 'work_dir': 'build'} 04:04:48 INFO - [mozharness: 2016-11-14 12:04:48.323808Z] Running clobber step. 04:04:48 INFO - Running pre-action listener: _resource_record_pre_action 04:04:48 INFO - Running main action method: clobber 04:04:48 INFO - rmtree: /builds/slave/test/build 04:04:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:04:50 INFO - Running post-action listener: _resource_record_post_action 04:04:50 INFO - [mozharness: 2016-11-14 12:04:50.814817Z] Finished clobber step (success) 04:04:50 INFO - [mozharness: 2016-11-14 12:04:50.814937Z] Running read-buildbot-config step. 04:04:50 INFO - Running pre-action listener: _resource_record_pre_action 04:04:50 INFO - Running main action method: read_buildbot_config 04:04:50 INFO - Using buildbot properties: 04:04:50 INFO - { 04:04:50 INFO - "project": "", 04:04:50 INFO - "product": "firefox", 04:04:50 INFO - "script_repo_revision": "production", 04:04:50 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:04:50 INFO - "repository": "", 04:04:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:04:50 INFO - "buildid": "20161114012320", 04:04:50 INFO - "pgo_build": "False", 04:04:50 INFO - "basedir": "/builds/slave/test", 04:04:50 INFO - "buildnumber": 8, 04:04:50 INFO - "slavename": "t-yosemite-r7-0006", 04:04:50 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 04:04:50 INFO - "platform": "yosemite_r7", 04:04:50 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:04:50 INFO - "branch": "mozilla-central", 04:04:50 INFO - "repo_path": "mozilla-central", 04:04:50 INFO - "revision": "1196bf3032e1bce1fb07a01fd9082a767426c5fb", 04:04:50 INFO - "stage_platform": "macosx64", 04:04:50 INFO - "builduid": "402628d536814718bdbdf641ed2ea43a", 04:04:50 INFO - "slavebuilddir": "test" 04:04:50 INFO - } 04:04:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 04:04:50 INFO - Running post-action listener: _resource_record_post_action 04:04:50 INFO - [mozharness: 2016-11-14 12:04:50.818268Z] Finished read-buildbot-config step (success) 04:04:50 INFO - [mozharness: 2016-11-14 12:04:50.818394Z] Running download-and-extract step. 04:04:50 INFO - Running pre-action listener: _resource_record_pre_action 04:04:50 INFO - Running main action method: download_and_extract 04:04:50 INFO - mkdir: /builds/slave/test/build/tests 04:04:50 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')]} 04:04:50 INFO - trying https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 04:04:50 INFO - Downloading https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/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 04:04:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/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 04:04:51 INFO - Downloaded 1199 bytes. 04:04:51 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:04:51 INFO - Using the following test package requirements: 04:04:51 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:04:51 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:04:51 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 04:04:51 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:04:51 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 04:04:51 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:04:51 INFO - u'jsshell-mac.zip'], 04:04:51 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:04:51 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 04:04:51 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:04:51 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:04:51 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 04:04:51 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:04:51 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 04:04:51 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:04:51 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 04:04:51 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:04:51 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 04:04:51 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:04:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:04:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:04:51 INFO - Fetch https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:04:52 INFO - Http code: 200 04:04:52 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 04:04:52 INFO - x-amz-cf-id: x3MnVpZanFmatHxdNIXephYDEiFcTnpc92CzgJ5D2sjNI3rnCVK4jg== 04:04:52 INFO - x-amz-version-id: sik5CXhVb3XERFj.nJVtYPDOWxpxqzFX 04:04:52 INFO - Expected file size: 16328778 04:04:52 INFO - Obtained file size: 16328778 04:04:59 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 04:04:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:04:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:04:59 INFO - Fetch https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:05:00 INFO - Http code: 200 04:05:00 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 04:05:00 INFO - x-amz-cf-id: RghN7WGCzdecn0a1ucSJuW1OUnPtYGtZ6pTF5-kYPHYsob_Qjd-sjg== 04:05:00 INFO - x-amz-version-id: sik5CXhVb3XERFj.nJVtYPDOWxpxqzFX 04:05:00 INFO - Expected file size: 16328778 04:05:00 INFO - Obtained file size: 16328778 04:05:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 04:05:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:05:06 INFO - Fetch https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 04:05:07 INFO - Http code: 200 04:05:07 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 04:05:07 INFO - x-amz-cf-id: QE1_fbrW8qBKnzVnDd4kjEx47PcieLJJZ3O_OcpZIWo7dEQekGmLQg== 04:05:07 INFO - x-amz-version-id: Xc5N8LgCrFBlCV6QdLMMYT9QkLNEpAlu 04:05:07 INFO - Expected file size: 14409038 04:05:07 INFO - Obtained file size: 14409038 04:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:08 INFO - trying https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:05:08 INFO - Downloading https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 04:05:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/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 04:05:11 INFO - Downloaded 110958929 bytes. 04:05:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:05:11 INFO - mkdir: /builds/slave/test/properties 04:05:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:05:11 INFO - Writing to file /builds/slave/test/properties/build_url 04:05:11 INFO - Contents: 04:05:11 INFO - build_url:https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:05:11 INFO - Running post-action listener: _resource_record_post_action 04:05:11 INFO - Running post-action listener: set_extra_try_arguments 04:05:11 INFO - [mozharness: 2016-11-14 12:05:11.775782Z] Finished download-and-extract step (success) 04:05:11 INFO - [mozharness: 2016-11-14 12:05:11.775937Z] Running populate-webroot step. 04:05:11 INFO - Running pre-action listener: _resource_record_pre_action 04:05:11 INFO - Running main action method: populate_webroot 04:05:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:05:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:05:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:05:11 INFO - '--tppagecycles', 04:05:11 INFO - '7'], 04:05:11 INFO - 'tests': ['tsvgm']}}, 04:05:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:05:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:05:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:05:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:05:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:05:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:05:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:11 INFO - 'talos_options': ['--disable-e10s'], 04:05:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:05:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:05:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:11 INFO - 'talos_options': ['--disable-e10s'], 04:05:11 INFO - 'tests': ['damp', 'tps']}, 04:05:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:11 INFO - 'tests': ['damp', 'tps']}, 04:05:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:05:11 INFO - 'tests': ['dromaeo_dom']}, 04:05:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:05:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:05:11 INFO - 'tests': ['basic_compositor_video']}, 04:05:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:05:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:05:11 INFO - 'tests': ['a11yr', 04:05:11 INFO - 'ts_paint', 04:05:11 INFO - 'tpaint', 04:05:11 INFO - 'sessionrestore', 04:05:11 INFO - 'sessionrestore_no_auto_restore', 04:05:11 INFO - 'tabpaint']}, 04:05:11 INFO - 'other-e10s': {'tests': ['a11yr', 04:05:11 INFO - 'ts_paint', 04:05:11 INFO - 'tpaint', 04:05:11 INFO - 'sessionrestore', 04:05:11 INFO - 'sessionrestore_no_auto_restore', 04:05:11 INFO - 'tabpaint']}, 04:05:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:05:11 INFO - 'ts_paint', 04:05:11 INFO - 'tpaint', 04:05:11 INFO - 'sessionrestore', 04:05:11 INFO - 'sessionrestore_no_auto_restore', 04:05:11 INFO - 'tabpaint']}, 04:05:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:05:11 INFO - 'ts_paint', 04:05:11 INFO - 'tpaint', 04:05:11 INFO - 'sessionrestore', 04:05:11 INFO - 'sessionrestore_no_auto_restore', 04:05:11 INFO - 'tabpaint']}, 04:05:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:05:11 INFO - 'tests': ['a11yr', 04:05:11 INFO - 'ts_paint', 04:05:11 INFO - 'tpaint', 04:05:11 INFO - 'sessionrestore', 04:05:11 INFO - 'sessionrestore_no_auto_restore', 04:05:11 INFO - 'tabpaint']}, 04:05:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:05:11 INFO - 'tests': ['a11yr', 04:05:11 INFO - 'ts_paint', 04:05:11 INFO - 'tpaint', 04:05:11 INFO - 'sessionrestore', 04:05:11 INFO - 'sessionrestore_no_auto_restore', 04:05:11 INFO - 'tabpaint']}, 04:05:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:05:11 INFO - 'tests': ['tsvgx', 04:05:11 INFO - 'tsvgr_opacity', 04:05:11 INFO - 'tart', 04:05:11 INFO - 'tscrollx', 04:05:11 INFO - 'cart']}, 04:05:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:05:11 INFO - 'tsvgr_opacity', 04:05:11 INFO - 'tart', 04:05:11 INFO - 'tscrollx', 04:05:11 INFO - 'cart']}, 04:05:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:11 INFO - 'talos_options': ['--disable-e10s'], 04:05:11 INFO - 'tests': ['tp5o']}, 04:05:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:11 INFO - 'tests': ['tp5o']}, 04:05:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:11 INFO - 'talos_options': ['--disable-e10s', 04:05:11 INFO - '--xperf_path', 04:05:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:05:11 INFO - 'tests': ['tp5n']}, 04:05:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:11 INFO - 'talos_options': ['--xperf_path', 04:05:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:05:11 INFO - 'tests': ['tp5n']}}, 04:05:11 INFO - 'talos.zip': {'path': '', 04:05:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:05:11 INFO - Running post-action listener: _resource_record_post_action 04:05:11 INFO - [mozharness: 2016-11-14 12:05:11.794282Z] Finished populate-webroot step (success) 04:05:11 INFO - [mozharness: 2016-11-14 12:05:11.794374Z] Running create-virtualenv step. 04:05:11 INFO - Running pre-action listener: _resource_record_pre_action 04:05:11 INFO - Running main action method: create_virtualenv 04:05:11 INFO - Creating virtualenv /builds/slave/test/build/venv 04:05:11 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 04:05:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:05:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:05:11 INFO - Using real prefix '/tools/python27' 04:05:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:05:12 INFO - Installing distribute.............................................................................................................................................................................................done. 04:05:15 INFO - Installing pip.................done. 04:05:15 INFO - Return code: 0 04:05:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:05:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:05:15 INFO - Reading from file tmpfile_stdout 04:05:15 INFO - Output received: 04:05:15 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) 04:05:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:05:15 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')]} 04:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:15 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')]} 04:05:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:15 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 0x10f883490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce8d63f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f697d30>, '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 0x10f4bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f68fb20>, '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.MzRdiwLXWq/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.Ne7TrzGqhs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 04:05:15 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 04:05:15 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 04:05:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzRdiwLXWq/Render', 04:05:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:15 INFO - 'HOME': '/Users/cltbld', 04:05:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:15 INFO - 'LOGNAME': 'cltbld', 04:05:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:15 INFO - 'MOZ_NO_REMOTE': '1', 04:05:15 INFO - 'NO_EM_RESTART': '1', 04:05:15 INFO - 'PAGER': '/bin/cat', 04:05:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:15 INFO - 'PWD': '/builds/slave/test', 04:05:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:15 INFO - 'SHELL': '/bin/bash', 04:05:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne7TrzGqhs/Listeners', 04:05:15 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:05:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:15 INFO - 'USER': 'cltbld', 04:05:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:15 INFO - 'XPC_FLAGS': '0x0', 04:05:15 INFO - 'XPC_SERVICE_NAME': '0', 04:05:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:05:16 INFO - Cleaning up... 04:05:16 INFO - Return code: 0 04:05:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f883490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce8d63f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f697d30>, '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 0x10f4bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f68fb20>, '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.MzRdiwLXWq/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.Ne7TrzGqhs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 04:05:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:05:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzRdiwLXWq/Render', 04:05:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:16 INFO - 'HOME': '/Users/cltbld', 04:05:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:16 INFO - 'LOGNAME': 'cltbld', 04:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:16 INFO - 'MOZ_NO_REMOTE': '1', 04:05:16 INFO - 'NO_EM_RESTART': '1', 04:05:16 INFO - 'PAGER': '/bin/cat', 04:05:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:16 INFO - 'PWD': '/builds/slave/test', 04:05:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:16 INFO - 'SHELL': '/bin/bash', 04:05:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne7TrzGqhs/Listeners', 04:05:16 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:05:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:16 INFO - 'USER': 'cltbld', 04:05:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:16 INFO - 'XPC_FLAGS': '0x0', 04:05:16 INFO - 'XPC_SERVICE_NAME': '0', 04:05:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:16 INFO - Downloading/unpacking psutil>=3.1.1 04:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:05:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:05:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:05:19 INFO - Installing collected packages: psutil 04:05:19 INFO - Running setup.py install for psutil 04:05:19 INFO - building 'psutil._psutil_osx' extension 04:05:19 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 04:05:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:05:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:05:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:05:20 INFO - building 'psutil._psutil_posix' extension 04:05:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:05:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:05:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:05:20 INFO - ^ 04:05:20 INFO - 1 warning generated. 04:05:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:05:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:05:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:05:20 INFO - Successfully installed psutil 04:05:20 INFO - Cleaning up... 04:05:20 INFO - Return code: 0 04:05:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:05:20 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')]} 04:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:20 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')]} 04:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:20 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 0x10f883490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce8d63f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f697d30>, '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 0x10f4bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f68fb20>, '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.MzRdiwLXWq/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.Ne7TrzGqhs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 04:05:20 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 04:05:20 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 04:05:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzRdiwLXWq/Render', 04:05:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:20 INFO - 'HOME': '/Users/cltbld', 04:05:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:20 INFO - 'LOGNAME': 'cltbld', 04:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:20 INFO - 'MOZ_NO_REMOTE': '1', 04:05:20 INFO - 'NO_EM_RESTART': '1', 04:05:20 INFO - 'PAGER': '/bin/cat', 04:05:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:20 INFO - 'PWD': '/builds/slave/test', 04:05:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:20 INFO - 'SHELL': '/bin/bash', 04:05:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne7TrzGqhs/Listeners', 04:05:20 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:05:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:20 INFO - 'USER': 'cltbld', 04:05:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:20 INFO - 'XPC_FLAGS': '0x0', 04:05:20 INFO - 'XPC_SERVICE_NAME': '0', 04:05:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:05:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:05:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:05:23 INFO - Installing collected packages: mozsystemmonitor 04:05:23 INFO - Running setup.py install for mozsystemmonitor 04:05:24 INFO - Successfully installed mozsystemmonitor 04:05:24 INFO - Cleaning up... 04:05:24 INFO - Return code: 0 04:05:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f883490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce8d63f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f697d30>, '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 0x10f4bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f68fb20>, '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.MzRdiwLXWq/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.Ne7TrzGqhs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 04:05:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:05:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:05:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzRdiwLXWq/Render', 04:05:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:24 INFO - 'HOME': '/Users/cltbld', 04:05:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:24 INFO - 'LOGNAME': 'cltbld', 04:05:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:24 INFO - 'MOZ_NO_REMOTE': '1', 04:05:24 INFO - 'NO_EM_RESTART': '1', 04:05:24 INFO - 'PAGER': '/bin/cat', 04:05:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:24 INFO - 'PWD': '/builds/slave/test', 04:05:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:24 INFO - 'SHELL': '/bin/bash', 04:05:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne7TrzGqhs/Listeners', 04:05:24 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:05:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:24 INFO - 'USER': 'cltbld', 04:05:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:24 INFO - 'XPC_FLAGS': '0x0', 04:05:24 INFO - 'XPC_SERVICE_NAME': '0', 04:05:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:24 INFO - Downloading/unpacking jsonschema==2.5.1 04:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:05:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:27 INFO - Downloading functools32-3.2.3-2.tar.gz 04:05:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:05:27 INFO - warning: no files found matching '*.txt' 04:05:27 INFO - no previously-included directories found matching 'build' 04:05:27 INFO - no previously-included directories found matching 'dist' 04:05:27 INFO - no previously-included directories found matching '.git*' 04:05:27 INFO - Installing collected packages: jsonschema, functools32 04:05:27 INFO - Running setup.py install for functools32 04:05:27 INFO - warning: no files found matching '*.txt' 04:05:27 INFO - no previously-included directories found matching 'build' 04:05:27 INFO - no previously-included directories found matching 'dist' 04:05:27 INFO - no previously-included directories found matching '.git*' 04:05:27 INFO - Successfully installed jsonschema functools32 04:05:27 INFO - Cleaning up... 04:05:27 INFO - Return code: 0 04:05:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f883490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce8d63f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f697d30>, '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 0x10f4bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f68fb20>, '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.MzRdiwLXWq/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.Ne7TrzGqhs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 04:05:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:05:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:05:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzRdiwLXWq/Render', 04:05:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:27 INFO - 'HOME': '/Users/cltbld', 04:05:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:27 INFO - 'LOGNAME': 'cltbld', 04:05:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:27 INFO - 'MOZ_NO_REMOTE': '1', 04:05:27 INFO - 'NO_EM_RESTART': '1', 04:05:27 INFO - 'PAGER': '/bin/cat', 04:05:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:27 INFO - 'PWD': '/builds/slave/test', 04:05:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:27 INFO - 'SHELL': '/bin/bash', 04:05:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne7TrzGqhs/Listeners', 04:05:27 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:05:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:27 INFO - 'USER': 'cltbld', 04:05:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:27 INFO - 'XPC_FLAGS': '0x0', 04:05:27 INFO - 'XPC_SERVICE_NAME': '0', 04:05:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:05:27 INFO - Cleaning up... 04:05:27 INFO - Return code: 0 04:05:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f883490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce8d63f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f697d30>, '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 0x10f4bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f68fb20>, '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.MzRdiwLXWq/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.Ne7TrzGqhs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 04:05:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:05:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:05:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzRdiwLXWq/Render', 04:05:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:27 INFO - 'HOME': '/Users/cltbld', 04:05:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:27 INFO - 'LOGNAME': 'cltbld', 04:05:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:27 INFO - 'MOZ_NO_REMOTE': '1', 04:05:27 INFO - 'NO_EM_RESTART': '1', 04:05:27 INFO - 'PAGER': '/bin/cat', 04:05:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:27 INFO - 'PWD': '/builds/slave/test', 04:05:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:27 INFO - 'SHELL': '/bin/bash', 04:05:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne7TrzGqhs/Listeners', 04:05:27 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:05:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:27 INFO - 'USER': 'cltbld', 04:05:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:27 INFO - 'XPC_FLAGS': '0x0', 04:05:27 INFO - 'XPC_SERVICE_NAME': '0', 04:05:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:28 INFO - Downloading/unpacking blobuploader==1.2.4 04:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:30 INFO - Downloading blobuploader-1.2.4.tar.gz 04:05:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:05:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:05: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 04:05: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 04:05: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 04:05: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 04:05: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 04:05: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 04:05:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:05:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:31 INFO - Downloading docopt-0.6.1.tar.gz 04:05:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:05:32 INFO - Installing collected packages: blobuploader, requests, docopt 04:05:32 INFO - Running setup.py install for blobuploader 04:05:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:05:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:05:32 INFO - Running setup.py install for requests 04:05:32 INFO - Running setup.py install for docopt 04:05:32 INFO - Successfully installed blobuploader requests docopt 04:05:32 INFO - Cleaning up... 04:05:32 INFO - Return code: 0 04:05:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:05: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')]} 04:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05: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')]} 04:05:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f883490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce8d63f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f697d30>, '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 0x10f4bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f68fb20>, '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.MzRdiwLXWq/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.Ne7TrzGqhs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 04:05:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:05:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzRdiwLXWq/Render', 04:05:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:32 INFO - 'HOME': '/Users/cltbld', 04:05:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:32 INFO - 'LOGNAME': 'cltbld', 04:05:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:32 INFO - 'MOZ_NO_REMOTE': '1', 04:05:32 INFO - 'NO_EM_RESTART': '1', 04:05:32 INFO - 'PAGER': '/bin/cat', 04:05:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:32 INFO - 'PWD': '/builds/slave/test', 04:05:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:32 INFO - 'SHELL': '/bin/bash', 04:05:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne7TrzGqhs/Listeners', 04:05:32 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:05:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:32 INFO - 'USER': 'cltbld', 04:05:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:32 INFO - 'XPC_FLAGS': '0x0', 04:05:32 INFO - 'XPC_SERVICE_NAME': '0', 04:05:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:05:33 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-rmrqos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:05:33 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-UHfREv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:05:33 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-sfCpzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:05:33 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-6XT3aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:05:33 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-tgJHV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:05:33 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-PdsNbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:05:33 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-OyGkBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:05:33 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Izv03W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:05:33 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-2o4TwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:05:34 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-qewYJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:05:34 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-cl_6IK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:05:34 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-66kjpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:05:34 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-5uAaaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:05:34 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-ohElKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:05:34 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-N5WZBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:05:34 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-shk_y6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:05:34 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-6U4WSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:05:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:05:34 INFO - Running setup.py install for manifestparser 04:05:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:05:35 INFO - Running setup.py install for mozcrash 04:05:35 INFO - Running setup.py install for mozdebug 04:05:35 INFO - Running setup.py install for mozdevice 04:05:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:05:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:05:35 INFO - Running setup.py install for mozfile 04:05:35 INFO - Running setup.py install for mozhttpd 04:05:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:05:35 INFO - Running setup.py install for mozinfo 04:05:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:05:35 INFO - Running setup.py install for mozInstall 04:05:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:05:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:05:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:05:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:05:36 INFO - Running setup.py install for mozleak 04:05:36 INFO - Running setup.py install for mozlog 04:05:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:05:36 INFO - Running setup.py install for moznetwork 04:05:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:05:36 INFO - Running setup.py install for mozprocess 04:05:36 INFO - Running setup.py install for mozprofile 04:05:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:05:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:05:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:05:36 INFO - Running setup.py install for mozrunner 04:05:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:05:37 INFO - Running setup.py install for mozscreenshot 04:05:37 INFO - Running setup.py install for moztest 04:05:37 INFO - Running setup.py install for mozversion 04:05:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:05:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:05:37 INFO - Cleaning up... 04:05:37 INFO - Return code: 0 04:05:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:05:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05: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')]} 04:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:38 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 0x10f883490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce8d63f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f697d30>, '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 0x10f4bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f68fb20>, '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.MzRdiwLXWq/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.Ne7TrzGqhs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 04:05:38 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 04:05:38 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 04:05:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzRdiwLXWq/Render', 04:05:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:38 INFO - 'HOME': '/Users/cltbld', 04:05:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:38 INFO - 'LOGNAME': 'cltbld', 04:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:38 INFO - 'MOZ_NO_REMOTE': '1', 04:05:38 INFO - 'NO_EM_RESTART': '1', 04:05:38 INFO - 'PAGER': '/bin/cat', 04:05:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:38 INFO - 'PWD': '/builds/slave/test', 04:05:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:38 INFO - 'SHELL': '/bin/bash', 04:05:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne7TrzGqhs/Listeners', 04:05:38 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:05:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:38 INFO - 'USER': 'cltbld', 04:05:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:38 INFO - 'XPC_FLAGS': '0x0', 04:05:38 INFO - 'XPC_SERVICE_NAME': '0', 04:05:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:05:38 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-P_gWqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:05:38 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)) 04:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:05:38 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-9rcQph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:05:39 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)) 04:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:05:39 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-lQuZsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:05:39 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)) 04:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:05:39 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-9DtX0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:05:39 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)) 04:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:05:39 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-rvtiXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:05:39 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)) 04:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:05:39 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-ijiBJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:05:39 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)) 04:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:05:39 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-9yHTqz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:05:39 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)) 04:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:05:39 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-zXUAvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:05:39 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)) 04:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:05:39 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-KNrxLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:05:39 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)) 04:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:05:39 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-RVeuli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:05:39 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)) 04:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:05:39 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-L0IXHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:05:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:05:40 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-F6ezsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:05:40 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)) 04:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:05:40 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-GBF9Yc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:05:40 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)) 04:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:05:40 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-eSHzvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:05:40 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)) 04:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:05:40 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-0ylxkj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:05:40 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)) 04:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:05:40 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-itI2tf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:05:40 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)) 04:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:05:40 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-dpV6pz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:05:40 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)) 04:05:40 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)) 04:05:40 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)) 04:05:40 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)) 04:05:40 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)) 04:05:40 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)) 04:05:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:05:40 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 04:05:40 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 04:05:40 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 04:05:40 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 04:05:40 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 04:05:40 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 04:05:43 INFO - Downloading blessings-1.6.tar.gz 04:05:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:05:43 INFO - Installing collected packages: blessings 04:05:43 INFO - Running setup.py install for blessings 04:05:43 INFO - Successfully installed blessings 04:05:43 INFO - Cleaning up... 04:05:43 INFO - Return code: 0 04:05:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:05:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:05:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:05:44 INFO - Reading from file tmpfile_stdout 04:05:44 INFO - Current package versions: 04:05:44 INFO - blessings == 1.6 04:05:44 INFO - blobuploader == 1.2.4 04:05:44 INFO - docopt == 0.6.1 04:05:44 INFO - functools32 == 3.2.3-2 04:05:44 INFO - jsonschema == 2.5.1 04:05:44 INFO - manifestparser == 1.1 04:05:44 INFO - mozInstall == 1.12 04:05:44 INFO - mozcrash == 1.0 04:05:44 INFO - mozdebug == 0.1 04:05:44 INFO - mozdevice == 0.48 04:05:44 INFO - mozfile == 1.2 04:05:44 INFO - mozhttpd == 0.7 04:05:44 INFO - mozinfo == 0.9 04:05:44 INFO - mozleak == 0.1 04:05:44 INFO - mozlog == 3.4 04:05:44 INFO - moznetwork == 0.27 04:05:44 INFO - mozprocess == 0.23 04:05:44 INFO - mozprofile == 0.28 04:05:44 INFO - mozrunner == 6.13 04:05:44 INFO - mozscreenshot == 0.1 04:05:44 INFO - mozsystemmonitor == 0.3 04:05:44 INFO - moztest == 0.7 04:05:44 INFO - mozversion == 1.4 04:05:44 INFO - psutil == 3.1.1 04:05:44 INFO - requests == 1.2.3 04:05:44 INFO - wsgiref == 0.1.2 04:05:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:05:44 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')]} 04:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:44 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')]} 04:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:44 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 0x10f883490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce8d63f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f697d30>, '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 0x10f4bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f68fb20>, '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.MzRdiwLXWq/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.Ne7TrzGqhs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 04:05:44 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 04:05:44 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 04:05:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzRdiwLXWq/Render', 04:05:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:44 INFO - 'HOME': '/Users/cltbld', 04:05:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:44 INFO - 'LOGNAME': 'cltbld', 04:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:44 INFO - 'MOZ_NO_REMOTE': '1', 04:05:44 INFO - 'NO_EM_RESTART': '1', 04:05:44 INFO - 'PAGER': '/bin/cat', 04:05:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:44 INFO - 'PWD': '/builds/slave/test', 04:05:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:44 INFO - 'SHELL': '/bin/bash', 04:05:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne7TrzGqhs/Listeners', 04:05:44 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:05:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:44 INFO - 'USER': 'cltbld', 04:05:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:44 INFO - 'XPC_FLAGS': '0x0', 04:05:44 INFO - 'XPC_SERVICE_NAME': '0', 04:05:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:44 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)) 04:05:44 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)) 04:05:44 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)) 04:05:44 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)) 04:05:44 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)) 04:05:44 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)) 04:05:44 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)) 04:05:44 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)) 04:05:44 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)) 04:05:44 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)) 04:05:44 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)) 04:05:44 INFO - Cleaning up... 04:05:44 INFO - Return code: 0 04:05:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:05:44 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')]} 04:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:44 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')]} 04:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:44 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 0x10f883490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce8d63f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f697d30>, '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 0x10f4bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f68fb20>, '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.MzRdiwLXWq/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.Ne7TrzGqhs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 04:05:44 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 04:05:44 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 04:05:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzRdiwLXWq/Render', 04:05:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:44 INFO - 'HOME': '/Users/cltbld', 04:05:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:44 INFO - 'LOGNAME': 'cltbld', 04:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:44 INFO - 'MOZ_NO_REMOTE': '1', 04:05:44 INFO - 'NO_EM_RESTART': '1', 04:05:44 INFO - 'PAGER': '/bin/cat', 04:05:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:44 INFO - 'PWD': '/builds/slave/test', 04:05:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:44 INFO - 'SHELL': '/bin/bash', 04:05:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne7TrzGqhs/Listeners', 04:05:44 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:05:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:44 INFO - 'USER': 'cltbld', 04:05:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:44 INFO - 'XPC_FLAGS': '0x0', 04:05:44 INFO - 'XPC_SERVICE_NAME': '0', 04:05:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:05:44 INFO - Cleaning up... 04:05:44 INFO - Return code: 0 04:05:44 INFO - Running post-action listener: _resource_record_post_action 04:05:44 INFO - Running post-action listener: _start_resource_monitoring 04:05:44 INFO - Starting resource monitoring. 04:05:44 INFO - [mozharness: 2016-11-14 12:05:44.741530Z] Finished create-virtualenv step (success) 04:05:44 INFO - [mozharness: 2016-11-14 12:05:44.742569Z] Running install step. 04:05:44 INFO - Running pre-action listener: _resource_record_pre_action 04:05:44 INFO - Running main action method: install 04:05:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:05:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:05:44 INFO - Reading from file tmpfile_stdout 04:05:45 INFO - Detecting whether we're running mozinstall >=1.0... 04:05:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:05:45 INFO - Reading from file tmpfile_stdout 04:05:45 INFO - Output received: 04:05:45 INFO - Usage: mozinstall [options] installer 04:05:45 INFO - Options: 04:05:45 INFO - -h, --help show this help message and exit 04:05:45 INFO - -d DEST, --destination=DEST 04:05:45 INFO - Directory to install application into. [default: 04:05:45 INFO - "/builds/slave/test"] 04:05:45 INFO - --app=APP Application being installed. [default: firefox] 04:05:45 INFO - mkdir: /builds/slave/test/build/application 04:05:45 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'] 04:05:45 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 04:06:19 INFO - Reading from file tmpfile_stdout 04:06:19 INFO - Output received: 04:06:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:06:19 INFO - Running post-action listener: _resource_record_post_action 04:06:19 INFO - [mozharness: 2016-11-14 12:06:19.691390Z] Finished install step (success) 04:06:19 INFO - [mozharness: 2016-11-14 12:06:19.691547Z] Running run-tests step. 04:06:19 INFO - Running pre-action listener: _resource_record_pre_action 04:06:19 INFO - Running main action method: run_tests 04:06:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:06:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:06:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:06:19 INFO - 2016-11-14 04:06:19.705 screenresolution[1642:7727] starting screenresolution argv=screenresolution get 04:06:19 INFO - 2016-11-14 04:06:19.708 screenresolution[1642:7727] Display 0: 1600x1200x32@60 04:06:19 INFO - 2016-11-14 04:06:19.714 screenresolution[1643:7729] starting screenresolution argv=screenresolution list 04:06:19 INFO - Available Modes on Display 0 04:06:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:06:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:06:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:06:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:06:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:06:19 INFO - Intel Iris: 04:06:19 INFO - Chipset Model: Intel Iris 04:06:19 INFO - Type: GPU 04:06:19 INFO - Bus: Built-In 04:06:19 INFO - VRAM (Dynamic, Max): 1536 MB 04:06:19 INFO - Vendor: Intel (0x8086) 04:06:19 INFO - Device ID: 0x0a2e 04:06:19 INFO - Revision ID: 0x0009 04:06:19 INFO - Displays: 04:06:19 INFO - Display: 04:06:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:06:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:06:19 INFO - Display Serial Number: 1600x1200 60 04:06:19 INFO - Main Display: Yes 04:06:19 INFO - Mirror: Off 04:06:19 INFO - Online: Yes 04:06:19 INFO - Rotation: Supported 04:06:19 INFO - Return code: 0 04:06:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:06:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:06:19 INFO - Python 2.7.3 04:06:19 INFO - Return code: 0 04:06:19 INFO - grabbing minidump binary from tooltool 04:06:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:06:19 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f697d30>, '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 0x10f4bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f68fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:06:19 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:06:19 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:06:19 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:06:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:06:20 INFO - Return code: 0 04:06:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:06:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:06:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:06:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:06:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:06:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:06:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0006 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:06:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzRdiwLXWq/Render', 04:06:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:06:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:06:20 INFO - 'HOME': '/Users/cltbld', 04:06:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:06:20 INFO - 'LOGNAME': 'cltbld', 04:06:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:06:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:06:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:20 INFO - 'MOZ_NO_REMOTE': '1', 04:06:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:06:20 INFO - 'NO_EM_RESTART': '1', 04:06:20 INFO - 'PAGER': '/bin/cat', 04:06:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:06:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:20 INFO - 'PWD': '/builds/slave/test', 04:06:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:06:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:06:20 INFO - 'SHELL': '/bin/bash', 04:06:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne7TrzGqhs/Listeners', 04:06:20 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:06:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:06:20 INFO - 'USER': 'cltbld', 04:06:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:06:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:06:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:20 INFO - 'XPC_FLAGS': '0x0', 04:06:20 INFO - 'XPC_SERVICE_NAME': '0', 04:06:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:06:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:06:20 INFO - mozversion application_buildid: 20161114012320 04:06:20 INFO - mozversion application_changeset: 1196bf3032e1bce1fb07a01fd9082a767426c5fb 04:06:20 INFO - mozversion application_display_name: Nightly 04:06:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:06:20 INFO - mozversion application_name: Firefox 04:06:20 INFO - mozversion application_remotingname: firefox 04:06:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:06:20 INFO - mozversion application_vendor: Mozilla 04:06:20 INFO - mozversion application_version: 52.0a1 04:06:20 INFO - mozversion platform_buildid: 20161114012320 04:06:20 INFO - mozversion platform_changeset: 1196bf3032e1bce1fb07a01fd9082a767426c5fb 04:06:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:06:20 INFO - mozversion platform_version: 52.0a1 04:06:20 INFO - using testdate: 1479125180 04:06:20 INFO - actual date: 1479125180 04:06:20 INFO - starting webserver on 'localhost:49277' 04:06:20 INFO - SUITE-START | Running 2 tests 04:06:20 INFO - TEST-START | dromaeo_css 04:06:20 INFO - operating with platform_type : mac_ 04:06:20 INFO - Initialising browser for dromaeo_css test... 04:06:20 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpO5Q8Z8/profile http://localhost:49277/getInfo.html) 04:06:22 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 04:06:22 INFO - PROCESS | 1653 | colorDepth:24 04:06:22 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 04:06:22 INFO - PROCESS | 1653 | __metrics 04:06:22 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:06:22 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:06:23 INFO - TEST-INFO | 1653: exit 0 04:06:23 INFO - Browser initialized. 04:06:23 INFO - Running cycle 1/1 for dromaeo_css test... 04:06:23 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpO5Q8Z8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:06:35 INFO - PROCESS | 1655 | RSS: Main: 238227456 04:06:35 INFO - PROCESS | 1655 | 04:07:26 INFO - PROCESS | 1655 | 1479125246809 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] 04:09:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 04:09:42 INFO - PROCESS | 1655 | RSS: Main: 520802304 04:09:42 INFO - PROCESS | 1655 | 04:12:45 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 04:12:46 INFO - PROCESS | 1655 | RSS: Main: 383893504 04:12:46 INFO - PROCESS | 1655 | 04:15:48 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 04:15:49 INFO - PROCESS | 1655 | RSS: Main: 354164736 04:15:49 INFO - PROCESS | 1655 | 04:18:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 04:18:52 INFO - PROCESS | 1655 | RSS: Main: 346673152 04:18:52 INFO - PROCESS | 1655 | 04:21:55 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 04:21:56 INFO - PROCESS | 1655 | RSS: Main: 340336640 04:21:56 INFO - PROCESS | 1655 | 04:24:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 04:25:00 INFO - PROCESS | 1655 | RSS: Main: 379318272 04:25:00 INFO - PROCESS | 1655 | 04:25:00 INFO - PROCESS | 1655 | __start_tp_report 04:25:00 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 04:25:00 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 04:25:00 INFO - PROCESS | 1655 | |i|pagename|runs| 04:25:00 INFO - PROCESS | 1655 | |0;dojo.html;3272.8036317820915;3304.7851889627173;3305.570275864138;3318.2368055347283;3332.4168270552645;5506.889862202754;5511.834644960652;5597.552195824334;5633.887322253558;5643.548516118711;4660.207764680004;4676.649251306158;5561.805336813212;5622.32532096148;5689.800856969997;5055.317532133152;5056.544910958013;5096.847094587169;5137.178051511759;5174.663646862942;3855.383138697809;5205.141151709963;5216.113260745663;5217.243499692561;5246.658967167122;5060.114479966028;5075.340205773263;5094.79620815167;5162.354705661811;5175.974120129413;5094.7197904115255;5106.872328191787;5126.307948426952;5148.304978827885;5156.20078887773;5106.872328191787;5107.61692873036;5148.021875843615;5148.073346797575;5176.094183517869;5499.285092937933;5532.861678458031;5580.720963951786;5593.636413633141;5613.410591887834;6151.415615516519;6151.661658608775;6161.784337548209;6166.599171053867;6179.19670442844;6152.723127459275;6157.846053848645;6214.471769899564;6220.595661282002;6226.2839773426185;5567.331920169565;5587.161925711176;5588.189712491666;5664.291963504562;5747.856303592402;6235.501159907197;6248.4376406123665;6255.718492667841;6263.467605253604;6265.843353916189;6000.639961602318;6013.398660133952;6017.608855424383;6030.500223535822;6075.696215189223;6088.786892458743;6094.481969032682;6117.143599896018;6183.319834818167;6185.319614842364;6041.063635136561;6077.36187700294;6086.695665216722;6093.025115981422;6097.054956481752;6097.433938678448;6121.938780612226;6126.509879209652;6132.41742034506;6140.17107690465;4847.418309802846;4878.658493905393;4884.169691152512;4889.266610008456;4892.3884514435695;4298.677599180011;4307.741535507879;4309.612134907873;4316.1583491219535;4317.050248945227;5622.522085622768;5647.915281270784;5650.3219613646625;5650.915236271459;5660.320761508646;6102.640737559575;6104.004449388181;6116.761878222105;6136.365905528278;6139.277491147187;5619.297587801525;5634.802781902614;5635.436456354414;5664.660120392789;5678.801241956512;5294.894102117936;5320.441353657888;5320.547753440924;5328.92006619901;5364.6512976656395;6225.782097626561;6235.407636274546;6240.063990401476;6252.718627661766;6278.968605156945;6126.945200426759;6162;6174.845628859314;6176.752929882754;6180.783672571439;6143.109249158936;6259.906101408392;6264.467520260647;6282.403171698681;6297.149884765498;5874.148248504029;5879.529637628894;5885.264341957255;6072.635641861502;6072.939270607238;6088.238970128734;6096.359882212481;6104.725287362168;6108.053251745987;6108.297545782114;4852.514748525119;4862.82980095695;5491.0116179088145;5804.361520232686;5832.096025116114;5843.065109582446;5880.911786323123;5908.940910591011;5910.202122713551;5911.881762364815;5737.540996720169;5744.798932037525;5752.07966725322;5753.453421924911;5758.856028599151;5100.515451032146;5836.970815145897;5863.677497737665;5987.34139244674;6013.128096425906;5858.502027327725;5881.794137205177;5894.823155305347;5897.351291357862;5899.203607512931 04:25:00 INFO - PROCESS | 1655 | |1;ext.html;472.7210945542129;480.86535769984374;508.43055777529173;509.13193005924893;537.3202898333611;37622;39031.80484097579;39390.60609393909;39547.406788898115;41517;40784.79607601965;41383.965400864916;41452.75641730753;41456;41460.37809432861;8309.584520773968;9183.311251656136;9347.205487533567;9366.391184572982;9373.437593744364;8407.075221725605;11009.779804403906;11286.604968826092;11347.773044539104;11380.089592832554;11294.491747871365;11361.545538178463;11399.48702308398;11401.714957126096;11404.201705880592;8378.287845533076;8597.054324024351;11224.831627525593;11332.6033588824;11342.979131878088;8405.915940840636;8537.871931921027;8591.914080859236;8606.397552171351;11246.831297530543;39247.822565323084;39557.20885582301;39562;39593.20813583712;39604.80197599022;3276.311974485361;3277.4428347181038;3297.159224397787;3299.4390953537954;3538.9115272118147;25497.872510637328;29464.705352946625;29718;30144.94492692767;30733.846330768425;3124.1626390639276;3148.7166155045925;3164.2247649326064;3231.676832316773;3244.4646633305474;450278;494790.052099478;502978;505483;507404;48748.26877596839;50986.62667166604;50987.90120987871;51023.74488127536;56586.585335366944;53448.19827702588;59767.103493447634;60811.39188608146;60973.08540371898;64415.03377449342;15299.61750956235;15323.923380383027;15525.767113493306;15546.76679849803;15551.22243887801;9917.851232231522;10749.936268163536;10946.945265273782;10967.341959482457;11932.761344773056;15978.60053498672;16083.758743618855;16326.510204693877;16516.42192523256;16769.91615041917;7174.8206294842;7176.677049532784;7177.4258059355125;7229.530080544748;7237.095363079615;785.6346798738614;902.3548163063441;980.9068138526828;1017.9338343007681;1055.4458909072798;27741.861290693418;28677.713222867922;28962.27594310159;29718;29766.851165744032;983.8783786483415;1018.8624535687735;1050.6427814543092;1055.1137044882337;1107.6234080412698;14690.632734181518;15008.549743507712;15153.621159471102;15185.760912485175;15364.462243821412;637273;639327;642065;643150;644391;56562.71718641381;56586;56706.71646641823;56738.7163064182;56799.58001050007;1216.2945491614842;1252.4426630149496;1254.5420921363818;1340.671535473815;1359.2252416122913;26760.866195668896;26860.05989790348;26874.59688104719;30901.8454907724;31628.05115846528;52295.95408091893;53130.749540037476;53160.93678126339;53250;53304.46695532995;536690;541442.2206778665;543309;543312.2834385802;543636;31692.366152677278;32447;33720.49419258664;37791.59875611208;38062.85811425662;36141.27717445689;44564.33153502635;45382.546174537834;45535;45775.77112114418;30027.399452011276;33610.159746006546;36291.45562816507;37477.81261093677;37656.43515347327;34502;37418.62581374151;37765.4335184978;38206.61793382142;38297.808510957264;36808.81595592005;36962;37512.437313439776;37566;37601.62398375981;26595.601065984414;32119.357612848078;32432.67567324391;33821.98534043982;33908.83045584855;21105.89447052755;24428.022879084638;24648.630270545596;25169.37076573027;25474.363140921625 04:25:00 INFO - PROCESS | 1655 | |2;jquery.html;349.3746194312178;365.8280608114186;365.86828741653;379.2471943192763;402.71809733186774;13728.519501817438;15109.168995705231;19576.804231957674;19607;20593.794062059376;19335.806641933577;19890.502737431572;19891.005449727527;20098.799012009913;20164.39506814798;5086.46592107828;5393.541548968323;5465;5481.342238931334;5497.505224529791;5422.132458806592;5464.12573988162;5629.887402251952;5957.910631340534;6004.54965877561;6070.119832624267;6072.51419886408;6073.453389194971;6087.421694938974;6094.207752992105;5799.391063938289;5981.49157321626;6053.667048312341;6057.606255593417;6060.939390606081;5016.398032236155;5827.737751801179;5835.883282334372;5838.883222335529;5840.503557197643;12194.878051219463;12758.872411275861;12764.234145951179;12771;12788.936055319757;2044.8875311857842;2139.4330502416874;2222.9555408891893;2481.3796550862285;2584.1988983415204;9559.330846840723;9714.465704386255;9788.853167202498;10138.847917281246;10273.12678422335;1566.4517418903406;1769.9557511062326;1798.6492633936257;1816.9636607267912;1896.0993528074055;88006.67989980092;99782.002179978;101204;117442.82557174405;119636;11053.502592383202;11158.497867595977;11236.775264494665;11261.155413343868;11406.657800266004;12281.754364912651;13020.414081366362;13032.869671303166;13193.868061319265;14074.085184462978;8099.149589293157;8153;8236.546989915492;8331.916680833236;8377.329813614897;6612.206535215709;8026;8041.718539851077;8177.9591102044105;8187.877181842277;4373.737575745466;4479.731216126977;4487.0929854811575;4547.113312903952;4556.589906908394;1850.2783914273443;1896.3173257627243;1900.5818719881604;1901.5911579010578;1908.0251939242817;816.2123550773532;881.2331275792124;893.8078313162702;931.9050116113675;951.6145960885852;17314.480565583053;18228;20686.896565517076;20699.58600827975;20712.58574828495;790.075611534506;794.5074054086504;933.9906600934039;951.5480146930153;961.1301771896445;10055.849162262572;10733.749269447659;10766.649791040085;10831.133509319236;10880;516510;541538;560041;566939.3306066969;570663;128406;128409.35795320963;131650.34174829064;132382.01426978604;138794;961.4423634292094;991.9900800991898;1010.8231059564547;1011.9241056920907;1013.4071568132578;18335.90832045831;19134.90432547884;19259.51851203731;19289.324873629357;19346.4196074118;54799;54898;56269;56452;57638.847223054465;67329.66335168293;80127.19872801198;82115.35769284461;82678.58660706658;84510.57744711237;7927.801804954922;7962.601869906597;8005.119436862057;8115.391345648982;8235.588220589067;7444.553326800409;12376.381180940736;12641.747165056831;12704.491820327083;13115.672108197372;10102.14131798776;10856.294340867818;11023.283486573348;11193.328400296008;11436.256643318158;10630.946845265724;10684;10730.356178629307;11059.61291354831;11286.66140015801;11176.944115279372;11219.439028048402;11292.588864861738;11351.943240284076;11528.365939873387;7897.802554935943;7929.444938854455;7975.72084977023;7990.200979902196;8025.638846252049;8099.797505062421;9627.759306017126;11025.338479691245;11187.216894817284;11210.383428911487 04:25:00 INFO - PROCESS | 1655 | |3;mootools.html;1325.2644782145906;1380.1650001748942;1473.8378778334384;1641.9096949667764;1849.3342396737173;7107.680154393051;7209.819754506133;7454.440916931231;8252.257296843298;8269.875951860728;8159.91840081599;8246.422750407473;8246.422750407473;8255.133211012831;8256.339492840574;2277.1005452846116;2315.536892621474;2317.80298674612;2319.8724070176136;2320.9419764505856;2242.3048854855047;2289.175896677195;2296.9310920672406;2302.4934514406805;2303.239930822833;2265.660150977358;2266.9433264168365;2305.101010605865;2306.7116610423695;2308.1921327535397;2204.415829805103;2208.116753298685;2232.0960011195493;2232.229880691167;2232.9888350558304;1973.6250112478583;2286.119843860126;2307.4462129089065;2308.676785250066;2314.722233331994;8227.958860205721;8290.129536398652;8298.8340233195;8311.044229913578;8311.792205194857;4977.875553111165;4997.225430058346;5166.199239117943;5167.534921857051;5168.8966220675375;3346.498025296201;3497.055794935379;3522.7357948153735;3603.4774957631257;3605.0086226287717;1020.7703266764919;1053.8893416191343;1085.978280434387;1087.1791797193148;1095.895889890459;9345.92521859988;10683.732906677395;10797.136229101652;10981;10987.285826421337;4167.333226683716;4374.147041326974;4429.11417716458;4431.468223813163;4450.866474005785;7345.485815992829;8632.611532481053;8644.91355086441;8655.826883462296;8661.30709543235;4941;5047.470015648304;5049.065922804293;5050.242463630485;5054.115529782273;4249.213895429293;4392.516823149451;4402.141582391467;4681.485036645966;4733.408323959505;3505.1762835733584;3572.1426857554;3635.527381440396;3665.4685070664605;3672.192117734094;1127.1658972360558;1128.8871112888646;1133.557912414159;1135.9716007099723;1141.303804679145;805.5327909812295;813.6297984417076;831.9168083191632;866.5147517390282;882.3426547222232;5306.814261500829;5591.692456914909;5759.22250496186;5775.509081728016;5812.360640329561;857.4169564696067;882.5366682491743;882.849915514364;893.647009431284;898.6046139698511;3253.5933008373954;3267.7909173605917;3675.320065787784;3720.1257704439436;3836.462895194675;9869.851952220723;10051.547680354255;10081.848772268422;10247.795044099077;10401.063904248502;10321.277510574339;10332.638357657446;10340.948295258475;10342.689719308282;10360.948195258976;7541.2458754124145;7818;7864.567448790257;7929;7963.76108716751;4829.124440519076;5584.832455026356;5639.689817060101;5685.403032681509;5773.96004630292;9361.578728957076;9420.387674801115;9464.763380915258;9526.857097143687;9625.470599116836;9447.338686292169;9542.52287385614;9543.379680320757;9567.9043209567;9688.612455501701;6226.25284965807;6443.774467893789;6461.321561236003;6682.63245521501;6864.759733409307;7390.630468476662;7727.381809455118;7888.2506161914525;7959.602019898866;8074.3944204183745;7076.964615176891;7198.136223653195;7232;7272.745453909088;7349.412047036331;6978.406835419046;6989.30106989297;6990.580565165903;7128.714851405885;7148;5678.659280443187;6641.667916604054;6697.229818570732;6706.329367063255;6714.9328506716265;4772.928406073842;4806.687565308243;4821.373221481325;4831.06757297084;4955.108080545537;4787.521247875185;4874.512548745097;4899.16714158601;4917.213245880642;4934.703917764946 04:25:00 INFO - PROCESS | 1655 | |4;prototype.html;256.3642167424786;299.06542056074767;303.4053255618986;309.64236307065346;313.36856234687093;5088.905885234673;5179.585633149349;5231.142944254787;5251.448597897213;5252.52727254547;4355.04189078403;4893.29047288143;4962.578180854623;5027.2710456983805;5078.9746051269785;3730.996547779037;3939.6559047366773;3942.8619998300064;3971.5035620547433;3988.421678856564;2971.2274808549823;3316.121227874615;3806.7437745544044;4030.455888455065;4033.7378070425475;3914.7651140931494;3936.3308237599676;3959.8220978461873;3982.283189025974;3988.544181373792;3320.7509436792216;3366.074329559366;3859.247446747885;3980.1641655252433;3983.3825757007567;3475.165960169541;3487;3509.596396414394;3512.964870351315;3514.5255390522207;4803.615710743132;4958.677685950402;5008.974955125237;5018.949810501885;5026.773795179189;3805.486259354979;3987.112565248888;4446.866594002185;4523.411956445674;4566.520515345907;4704.741723972513;4872.344572208155;5322.680639161663;5424.969255841377;5428.511433970962;3826.2730081284467;3843.2121415110114;3846.307664620367;3846.4614953906475;3848.8845334640005;4736.384270044872;5275.947240527584;5659.717014149276;5663.461971112738;5676.971615141939;3174.555562221291;3500.579930408367;3766.322062028861;3786.924261514754;3799.430085487199;3841.635044670752;3845.9038524036564;3860.2086572253183;3873.24471728016;3873.3996230584303;3614.4397618369194;4454.7549884756;4505.166544189336;4528.388667529907;4538.29656403258;5385.569154467633;5475.521609165503;5478.657728856456;5495.230667706524;5509.393966663663;1046.7958748044211;1073.436445865911;1124.4490199802037;1227.8096894981293;1228.2129501575187;661.7055410342448;717.9338682057141;775.0350813237555;788.9723859664884;801.5351096364095;4196.3982330954395;4239.04621460169;4390.85837682205;4544.681872268976;4910.772306923269;5547.805826796042;5619.719014049363;5630.662160270397;5653.152027195953;5661.443103146586;4438.356438316426;4600.090962250695;4885.951140488621;5103.234514822732;5130.717810520383;2603.71116297434;2622.1871219921886;2755.600437936488;2782.4296019315993;2801.9159425217276;4565.0641618468135;4582.15099316569;4748.430188377348;5371.093261892051;5630.6058575900115;4141.6065473779945;4284.485861696617;5061.44324124346;5362.785488580491;5514.972425137928;4778.498257682893;5027.265593370282;5325.653832500875;5503.614746967673;5579.46995035471;4409.382686423839;4969.602431805519;5278.947210527999;5287.629865909349;5325.786968521215;4643.8374656886845;4772.665913386029;5534.695591742332;5580.632744977506;5614.69119198448;5141.38303403594;5872.265966754156;5883.499902508163;5891.675957822361;5900.675462849585;3710.9940947832342;3788.2156614888586;3824.4263360496147;3850.210706805154;3856.382978723391;4926.852194434173;5369.892602148013;5595.272614560081;5643.576731745217;5671.914921276267;5483.643563168382;5486.698231597141;5493.23094766741;5511.944880551143;5513.4589737313745;4578.070035177422;4810.542998415145;5240.554552863049;5456.236126942152;5511.409637470382;4665.393498845128;5150.819721309736;5278.366596008504;5337.359516858002;5372.677639341652;4964.925526117039;5180.6891586503425;5423.62034657586;5484.698341591251;5525.309336332958;5396.163594642837;5397.596624877481;5401;5407.297051383295;5448.182772584144 04:25:00 INFO - PROCESS | 1655 | |5;yui.html;682.5631595778701;732.103173612325;768.9961550192248;770.907491101068;775.5153029356652;13687.24720140392;14062.226577538231;14507.782383264259;14826.777598336033;14910.925445372757;13092.672683182902;13485.865141348584;13497.122687025361;13499.66250843727;13513.121647092932;808.4219782855272;820.3478234803346;832.7876391520153;842.6334544473174;844.1937949258444;656.8193746719643;672.6569449580725;673.4006734006714;835.6448509383519;835.8537255980209;704.9083619129506;708.2174197511753;708.6492186367756;843.3337663246028;863.2403484933282;869.4739682492096;869.8042940338437;871.1984973824098;871.6905498548051;871.7951281448886;578.973946172419;642.383312020461;644.8065580325883;644.9774257900998;645.9806205813833;11365.772684546346;11818.586349477728;13529;13899.235542045184;13935.651608709763;12872.485100595964;13232.735345293137;13288.26914519701;13412;13537.729245415037;11043.72390690231;11418.42907854604;11534.192606517547;11624.767504649944;11731;3618.0412190769466;3619.348517266891;3643.544556930384;3699.5005674233903;3766.0773110587807;89065;92433.53783231108;92503;92657;94390.52804736001;10056.34633748804;10099.747506312255;10290.691279261635;10452.581896724047;10722.03501684852;18706.71939920902;18859.811401885807;18963.62072758537;19135.617287654168;20280.391588252376;7414.14737305206;7536.321731044232;7673.884891726629;7682.7311044113185;7836.804079898048;4137.2552940471005;4205.432266643964;4219.198352313051;4241.384999175102;4369.016971181522;2845.701201373867;3146.496560550301;3303.174206448388;3323.3519463704347;3356.865725370958;657.1260223902199;667.5794249622707;674.1674032569772;677.3362105136994;679.7077256779633;506.2684421011603;642.8521440068716;648.9480841532666;657.9638119903451;675.3482889012029;7609.771706848804;7855.450118491765;8539.100273803726;8540.914590854012;8960.059193784558;572.461885827321;612.6936531734133;615.6244690738646;642.6588395389209;658.1312667279241;4198.853040143641;4264.466941632296;4461;4483.574060464251;4525.842741413124;90766;110207;111841.88158118314;116522;117531;65766;70962.29037709557;88096.559517202;89766.55116724374;98863;73093.90359144617;84023;86942.56528717316;87791;88602.55698721594;8480.32157427392;8506.234438900528;8556.962278391082;8710.69512567057;8817.955910220408;37722;40663.39004914869;43341.13317733564;49720.005599887074;54977.1753423691;60546.69726651339;80319.59840200996;80776.59611701904;80915.78626320728;81926.59036704779;11077.778444430905;11095.167862410513;11248.94375528117;11491.712707182387;11617.361045142261;11901.583444579399;12053.397330133634;12229.510819567118;12354;12602.810957835824;11673.533058677898;12022.038236941198;12031;12109.939450302692;12197.69505762363;11415.885841141815;11770.17608767378;11871.406429678655;11983.28100313984;12013.939930300643;11426.314421134759;11755.588554400554;11814.645560633195;11869.940650296694;11954.76090478203;9503.334766566273;9627.546389298252;9851.556679949275;9882.555285012048;10038.849417258893;10691.946540267249;10943.671689849318;11075.944620276847;11319.660410187707;11373 04:25:00 INFO - PROCESS | 1655 | __end_tp_report 04:25:00 INFO - PROCESS | 1655 | __start_cc_report 04:25:00 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,3526 04:25:00 INFO - PROCESS | 1655 | __end_cc_report 04:25:00 INFO - PROCESS | 1655 | __startTimestamp1479126300018__endTimestamp 04:25:00 INFO - PROCESS | 1655 | 04:25:00 INFO - PROCESS | 1655 | ------- Summary: start ------- 04:25:00 INFO - PROCESS | 1655 | Number of tests: 6 04:25:00 INFO - PROCESS | 1655 | 04:25:00 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5606.90 Median:5753.45 stddev:653.30 (11.4%) stddev-sans-first:629.12 04:25:00 INFO - PROCESS | 1655 | Values: 3272.8 3304.8 3305.6 3318.2 3332.4 5506.9 5511.8 5597.6 5633.9 5643.5 4660.2 4676.6 5561.8 5622.3 5689.8 5055.3 5056.5 5096.8 5137.2 5174.7 3855.4 5205.1 5216.1 5217.2 5246.7 5060.1 5075.3 5094.8 5162.4 5176.0 5094.7 5106.9 5126.3 5148.3 5156.2 5106.9 5107.6 5148.0 5148.1 5176.1 5499.3 5532.9 5580.7 5593.6 5613.4 6151.4 6151.7 6161.8 6166.6 6179.2 6152.7 6157.8 6214.5 6220.6 6226.3 5567.3 5587.2 5588.2 5664.3 5747.9 6235.5 6248.4 6255.7 6263.5 6265.8 6000.6 6013.4 6017.6 6030.5 6075.7 6088.8 6094.5 6117.1 6183.3 6185.3 6041.1 6077.4 6086.7 6093.0 6097.1 6097.4 6121.9 6126.5 6132.4 6140.2 4847.4 4878.7 4884.2 4889.3 4892.4 4298.7 4307.7 4309.6 4316.2 4317.1 5622.5 5647.9 5650.3 5650.9 5660.3 6102.6 6104.0 6116.8 6136.4 6139.3 5619.3 5634.8 5635.4 5664.7 5678.8 5294.9 5320.4 5320.5 5328.9 5364.7 6225.8 6235.4 6240.1 6252.7 6279.0 6126.9 6162.0 6174.8 6176.8 6180.8 6143.1 6259.9 6264.5 6282.4 6297.1 5874.1 5879.5 5885.3 6072.6 6072.9 6088.2 6096.4 6104.7 6108.1 6108.3 4852.5 4862.8 5491.0 5804.4 5832.1 5843.1 5880.9 5908.9 5910.2 5911.9 5737.5 5744.8 5752.1 5753.5 5758.9 5100.5 5837.0 5863.7 5987.3 6013.1 5858.5 5881.8 5894.8 5897.4 5899.2 04:25:00 INFO - PROCESS | 1655 | 04:25:00 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:68685.91 Median:37539.22 stddev:150167.84 (400.0%) stddev-sans-first:150501.76 04:25:00 INFO - PROCESS | 1655 | Values: 472.7 480.9 508.4 509.1 537.3 37622.0 39031.8 39390.6 39547.4 41517.0 40784.8 41384.0 41452.8 41456.0 41460.4 8309.6 9183.3 9347.2 9366.4 9373.4 8407.1 11009.8 11286.6 11347.8 11380.1 11294.5 11361.5 11399.5 11401.7 11404.2 8378.3 8597.1 11224.8 11332.6 11343.0 8405.9 8537.9 8591.9 8606.4 11246.8 39247.8 39557.2 39562.0 39593.2 39604.8 3276.3 3277.4 3297.2 3299.4 3538.9 25497.9 29464.7 29718.0 30144.9 30733.8 3124.2 3148.7 3164.2 3231.7 3244.5 450278.0 494790.1 502978.0 505483.0 507404.0 48748.3 50986.6 50987.9 51023.7 56586.6 53448.2 59767.1 60811.4 60973.1 64415.0 15299.6 15323.9 15525.8 15546.8 15551.2 9917.9 10749.9 10946.9 10967.3 11932.8 15978.6 16083.8 16326.5 16516.4 16769.9 7174.8 7176.7 7177.4 7229.5 7237.1 785.6 902.4 980.9 1017.9 1055.4 27741.9 28677.7 28962.3 29718.0 29766.9 983.9 1018.9 1050.6 1055.1 1107.6 14690.6 15008.5 15153.6 15185.8 15364.5 637273.0 639327.0 642065.0 643150.0 644391.0 56562.7 56586.0 56706.7 56738.7 56799.6 1216.3 1252.4 1254.5 1340.7 1359.2 26760.9 26860.1 26874.6 30901.8 31628.1 52296.0 53130.7 53160.9 53250.0 53304.5 536690.0 541442.2 543309.0 543312.3 543636.0 31692.4 32447.0 33720.5 37791.6 38062.9 36141.3 44564.3 45382.5 45535.0 45775.8 30027.4 33610.2 36291.5 37477.8 37656.4 34502.0 37418.6 37765.4 38206.6 38297.8 36808.8 36962.0 37512.4 37566.0 37601.6 26595.6 32119.4 32432.7 33822.0 33908.8 21105.9 24428.0 24648.6 25169.4 25474.4 04:25:00 INFO - PROCESS | 1655 | 04:25:00 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:32968.74 Median:357.60 stddev:92502.84 (25867.6%) stddev-sans-first:92729.91 04:25:00 INFO - PROCESS | 1655 | Values: 349.4 365.8 365.9 379.2 402.7 13728.5 15109.2 19576.8 19607.0 20593.8 19335.8 19890.5 19891.0 20098.8 20164.4 5086.5 5393.5 5465.0 5481.3 5497.5 5422.1 5464.1 5629.9 5957.9 6004.5 6070.1 6072.5 6073.5 6087.4 6094.2 5799.4 5981.5 6053.7 6057.6 6060.9 5016.4 5827.7 5835.9 5838.9 5840.5 12194.9 12758.9 12764.2 12771.0 12788.9 2044.9 2139.4 2223.0 2481.4 2584.2 9559.3 9714.5 9788.9 10138.8 10273.1 1566.5 1770.0 1798.6 1817.0 1896.1 88006.7 99782.0 101204.0 117442.8 119636.0 11053.5 11158.5 11236.8 11261.2 11406.7 12281.8 13020.4 13032.9 13193.9 14074.1 8099.1 8153.0 8236.5 8331.9 8377.3 6612.2 8026.0 8041.7 8178.0 8187.9 4373.7 4479.7 4487.1 4547.1 4556.6 1850.3 1896.3 1900.6 1901.6 1908.0 816.2 881.2 893.8 931.9 951.6 17314.5 18228.0 20686.9 20699.6 20712.6 790.1 794.5 934.0 951.5 961.1 10055.8 10733.7 10766.6 10831.1 10880.0 516510.0 541538.0 560041.0 566939.3 570663.0 128406.0 128409.4 131650.3 132382.0 138794.0 961.4 992.0 1010.8 1011.9 1013.4 18335.9 19134.9 19259.5 19289.3 19346.4 54799.0 54898.0 56269.0 56452.0 57638.8 67329.7 80127.2 82115.4 82678.6 84510.6 7927.8 7962.6 8005.1 8115.4 8235.6 7444.6 12376.4 12641.7 12704.5 13115.7 10102.1 10856.3 11023.3 11193.3 11436.3 10630.9 10684.0 10730.4 11059.6 11286.7 11176.9 11219.4 11292.6 11351.9 11528.4 7897.8 7929.4 7975.7 7990.2 8025.6 8099.8 9627.8 11025.3 11187.2 11210.4 04:25:00 INFO - PROCESS | 1655 | 04:25:00 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5358.42 Median:4987.55 stddev:2964.01 (59.4%) stddev-sans-first:2956.82 04:25:00 INFO - PROCESS | 1655 | Values: 1325.3 1380.2 1473.8 1641.9 1849.3 7107.7 7209.8 7454.4 8252.3 8269.9 8159.9 8246.4 8246.4 8255.1 8256.3 2277.1 2315.5 2317.8 2319.9 2320.9 2242.3 2289.2 2296.9 2302.5 2303.2 2265.7 2266.9 2305.1 2306.7 2308.2 2204.4 2208.1 2232.1 2232.2 2233.0 1973.6 2286.1 2307.4 2308.7 2314.7 8228.0 8290.1 8298.8 8311.0 8311.8 4977.9 4997.2 5166.2 5167.5 5168.9 3346.5 3497.1 3522.7 3603.5 3605.0 1020.8 1053.9 1086.0 1087.2 1095.9 9345.9 10683.7 10797.1 10981.0 10987.3 4167.3 4374.1 4429.1 4431.5 4450.9 7345.5 8632.6 8644.9 8655.8 8661.3 4941.0 5047.5 5049.1 5050.2 5054.1 4249.2 4392.5 4402.1 4681.5 4733.4 3505.2 3572.1 3635.5 3665.5 3672.2 1127.2 1128.9 1133.6 1136.0 1141.3 805.5 813.6 831.9 866.5 882.3 5306.8 5591.7 5759.2 5775.5 5812.4 857.4 882.5 882.8 893.6 898.6 3253.6 3267.8 3675.3 3720.1 3836.5 9869.9 10051.5 10081.8 10247.8 10401.1 10321.3 10332.6 10340.9 10342.7 10360.9 7541.2 7818.0 7864.6 7929.0 7963.8 4829.1 5584.8 5639.7 5685.4 5774.0 9361.6 9420.4 9464.8 9526.9 9625.5 9447.3 9542.5 9543.4 9567.9 9688.6 6226.3 6443.8 6461.3 6682.6 6864.8 7390.6 7727.4 7888.3 7959.6 8074.4 7077.0 7198.1 7232.0 7272.7 7349.4 6978.4 6989.3 6990.6 7128.7 7148.0 5678.7 6641.7 6697.2 6706.3 6714.9 4772.9 4806.7 4821.4 4831.1 4955.1 4787.5 4874.5 4899.2 4917.2 4934.7 04:25:00 INFO - PROCESS | 1655 | 04:25:00 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4342.57 Median:4807.08 stddev:1345.21 (28.0%) stddev-sans-first:1313.55 04:25:00 INFO - PROCESS | 1655 | Values: 256.4 299.1 303.4 309.6 313.4 5088.9 5179.6 5231.1 5251.4 5252.5 4355.0 4893.3 4962.6 5027.3 5079.0 3731.0 3939.7 3942.9 3971.5 3988.4 2971.2 3316.1 3806.7 4030.5 4033.7 3914.8 3936.3 3959.8 3982.3 3988.5 3320.8 3366.1 3859.2 3980.2 3983.4 3475.2 3487.0 3509.6 3513.0 3514.5 4803.6 4958.7 5009.0 5018.9 5026.8 3805.5 3987.1 4446.9 4523.4 4566.5 4704.7 4872.3 5322.7 5425.0 5428.5 3826.3 3843.2 3846.3 3846.5 3848.9 4736.4 5275.9 5659.7 5663.5 5677.0 3174.6 3500.6 3766.3 3786.9 3799.4 3841.6 3845.9 3860.2 3873.2 3873.4 3614.4 4454.8 4505.2 4528.4 4538.3 5385.6 5475.5 5478.7 5495.2 5509.4 1046.8 1073.4 1124.4 1227.8 1228.2 661.7 717.9 775.0 789.0 801.5 4196.4 4239.0 4390.9 4544.7 4910.8 5547.8 5619.7 5630.7 5653.2 5661.4 4438.4 4600.1 4886.0 5103.2 5130.7 2603.7 2622.2 2755.6 2782.4 2801.9 4565.1 4582.2 4748.4 5371.1 5630.6 4141.6 4284.5 5061.4 5362.8 5515.0 4778.5 5027.3 5325.7 5503.6 5579.5 4409.4 4969.6 5278.9 5287.6 5325.8 4643.8 4772.7 5534.7 5580.6 5614.7 5141.4 5872.3 5883.5 5891.7 5900.7 3711.0 3788.2 3824.4 3850.2 3856.4 4926.9 5369.9 5595.3 5643.6 5671.9 5483.6 5486.7 5493.2 5511.9 5513.5 4578.1 4810.5 5240.6 5456.2 5511.4 4665.4 5150.8 5278.4 5337.4 5372.7 4964.9 5180.7 5423.6 5484.7 5525.3 5396.2 5397.6 5401.0 5407.3 5448.2 04:25:00 INFO - PROCESS | 1655 | 04:25:00 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:19778.61 Median:4472.29 stddev:29506.19 (659.8%) stddev-sans-first:29554.12 04:25:00 INFO - PROCESS | 1655 | Values: 682.6 732.1 769.0 770.9 775.5 13687.2 14062.2 14507.8 14826.8 14910.9 13092.7 13485.9 13497.1 13499.7 13513.1 808.4 820.3 832.8 842.6 844.2 656.8 672.7 673.4 835.6 835.9 704.9 708.2 708.6 843.3 863.2 869.5 869.8 871.2 871.7 871.8 579.0 642.4 644.8 645.0 646.0 11365.8 11818.6 13529.0 13899.2 13935.7 12872.5 13232.7 13288.3 13412.0 13537.7 11043.7 11418.4 11534.2 11624.8 11731.0 3618.0 3619.3 3643.5 3699.5 3766.1 89065.0 92433.5 92503.0 92657.0 94390.5 10056.3 10099.7 10290.7 10452.6 10722.0 18706.7 18859.8 18963.6 19135.6 20280.4 7414.1 7536.3 7673.9 7682.7 7836.8 4137.3 4205.4 4219.2 4241.4 4369.0 2845.7 3146.5 3303.2 3323.4 3356.9 657.1 667.6 674.2 677.3 679.7 506.3 642.9 648.9 658.0 675.3 7609.8 7855.5 8539.1 8540.9 8960.1 572.5 612.7 615.6 642.7 658.1 4198.9 4264.5 4461.0 4483.6 4525.8 90766.0 110207.0 111841.9 116522.0 117531.0 65766.0 70962.3 88096.6 89766.6 98863.0 73093.9 84023.0 86942.6 87791.0 88602.6 8480.3 8506.2 8557.0 8710.7 8818.0 37722.0 40663.4 43341.1 49720.0 54977.2 60546.7 80319.6 80776.6 80915.8 81926.6 11077.8 11095.2 11248.9 11491.7 11617.4 11901.6 12053.4 12229.5 12354.0 12602.8 11673.5 12022.0 12031.0 12109.9 12197.7 11415.9 11770.2 11871.4 11983.3 12013.9 11426.3 11755.6 11814.6 11869.9 11954.8 9503.3 9627.5 9851.6 9882.6 10038.8 10691.9 10943.7 11075.9 11319.7 11373.0 04:25:00 INFO - PROCESS | 1655 | -------- Summary: end -------- 04:25:00 INFO - PROCESS | 1655 | 04:25:00 INFO - TEST-INFO | 1655: exit 0 04:25:00 INFO - TEST-OK | dromaeo_css | took 1120401ms 04:25:00 INFO - TEST-START | kraken 04:25:00 INFO - operating with platform_type : mac_ 04:25:00 INFO - Initialising browser for kraken test... 04:25:00 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpHDTm5w/profile http://localhost:49277/getInfo.html) 04:25:02 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 04:25:02 INFO - PROCESS | 1661 | colorDepth:24 04:25:02 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 04:25:02 INFO - PROCESS | 1661 | __metrics 04:25:02 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:25:02 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:25:03 INFO - TEST-INFO | 1661: exit 0 04:25:03 INFO - Browser initialized. 04:25:03 INFO - Running cycle 1/1 for kraken test... 04:25:03 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpHDTm5w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:25:15 INFO - PROCESS | 1662 | RSS: Main: 230899712 04:25:15 INFO - PROCESS | 1662 | 04:25:18 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 04:25:19 INFO - PROCESS | 1662 | RSS: Main: 289431552 04:25:19 INFO - PROCESS | 1662 | 04:25:21 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 04:25:22 INFO - PROCESS | 1662 | RSS: Main: 290607104 04:25:22 INFO - PROCESS | 1662 | 04:25:26 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 04:25:26 INFO - PROCESS | 1662 | RSS: Main: 381489152 04:25:26 INFO - PROCESS | 1662 | 04:25:28 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 04:25:29 INFO - PROCESS | 1662 | RSS: Main: 283262976 04:25:29 INFO - PROCESS | 1662 | 04:25:31 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:25:31 INFO - PROCESS | 1662 | RSS: Main: 288288768 04:25:31 INFO - PROCESS | 1662 | 04:25:34 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 04:25:35 INFO - PROCESS | 1662 | RSS: Main: 329748480 04:25:35 INFO - PROCESS | 1662 | 04:25:38 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 04:25:38 INFO - PROCESS | 1662 | RSS: Main: 332603392 04:25:38 INFO - PROCESS | 1662 | 04:25:41 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 04:25:42 INFO - PROCESS | 1662 | RSS: Main: 327995392 04:25:42 INFO - PROCESS | 1662 | 04:25:43 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:25:44 INFO - PROCESS | 1662 | RSS: Main: 304259072 04:25:44 INFO - PROCESS | 1662 | 04:25:46 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:25:47 INFO - PROCESS | 1662 | RSS: Main: 305881088 04:25:47 INFO - PROCESS | 1662 | 04:25:49 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:25:50 INFO - PROCESS | 1662 | RSS: Main: 313417728 04:25:50 INFO - PROCESS | 1662 | 04:25:52 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:25:53 INFO - PROCESS | 1662 | RSS: Main: 330489856 04:25:53 INFO - PROCESS | 1662 | 04:25:56 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:25:57 INFO - PROCESS | 1662 | RSS: Main: 324243456 04:25:57 INFO - PROCESS | 1662 | 04:25:59 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 04:25:59 INFO - PROCESS | 1662 | RSS: Main: 368173056 04:25:59 INFO - PROCESS | 1662 | 04:25:59 INFO - PROCESS | 1662 | __start_tp_report 04:25:59 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 04:25:59 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 04:25:59 INFO - PROCESS | 1662 | |i|pagename|runs| 04:25:59 INFO - PROCESS | 1662 | |0;ai-astar;103;114;114;114;114;114;113;113;114;113 04:25:59 INFO - PROCESS | 1662 | |1;audio-beat-detection;143;109;113;149;109;106;112;99;163;107 04:25:59 INFO - PROCESS | 1662 | |2;audio-dft;185;182;184;183;182;185;181;181;183;183 04:25:59 INFO - PROCESS | 1662 | |3;audio-fft;77;61;59;88;65;59;58;59;81;58 04:25:59 INFO - PROCESS | 1662 | |4;audio-oscillator;84;71;91;70;69;69;90;67;69;92 04:25:59 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;117;129;102;88;91;88;91;88;91;87 04:25:59 INFO - PROCESS | 1662 | |6;imaging-darkroom;100;100;101;103;101;99;101;103;101;100 04:25:59 INFO - PROCESS | 1662 | |7;imaging-desaturate;81;82;82;83;83;83;84;84;83;83 04:25:59 INFO - PROCESS | 1662 | |8;json-parse-financial;56;53;53;53;55;55;55;54;55;55 04:25:59 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;65;70;99;110;105;69;70;71;69;70 04:25:59 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;91;86;87;90;87;87;88;87;92;95 04:25:59 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;149;149;151;157;148;148;158;153;189;147 04:25:59 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;173;177;176;165;173;172;175;173;175;172 04:25:59 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;67;68;67;66;67;69;67;67;66;67 04:25:59 INFO - PROCESS | 1662 | __end_tp_report 04:25:59 INFO - PROCESS | 1662 | __start_cc_report 04:25:59 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2045 04:25:59 INFO - PROCESS | 1662 | __end_cc_report 04:25:59 INFO - PROCESS | 1662 | __startTimestamp1479126359259__endTimestamp 04:25:59 INFO - PROCESS | 1662 | 04:25:59 INFO - PROCESS | 1662 | ------- Summary: start ------- 04:25:59 INFO - PROCESS | 1662 | Number of tests: 14 04:25:59 INFO - PROCESS | 1662 | 04:25:59 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:112.60 Median:114.00 stddev:3.41 (3.0%) stddev-sans-first:0.50 04:25:59 INFO - PROCESS | 1662 | Values: 103.0 114.0 114.0 114.0 114.0 114.0 113.0 113.0 114.0 113.0 04:25:59 INFO - PROCESS | 1662 | 04:25:59 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:121.00 Median:128.00 stddev:22.04 (17.2%) stddev-sans-first:21.89 04:25:59 INFO - PROCESS | 1662 | Values: 143.0 109.0 113.0 149.0 109.0 106.0 112.0 99.0 163.0 107.0 04:25:59 INFO - PROCESS | 1662 | 04:25:59 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.00 stddev:1.45 (0.8%) stddev-sans-first:1.32 04:25:59 INFO - PROCESS | 1662 | Values: 185.0 182.0 184.0 183.0 182.0 185.0 181.0 181.0 183.0 183.0 04:25:59 INFO - PROCESS | 1662 | 04:25:59 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:66.50 Median:63.00 stddev:11.20 (17.8%) stddev-sans-first:11.21 04:25:59 INFO - PROCESS | 1662 | Values: 77.0 61.0 59.0 88.0 65.0 59.0 58.0 59.0 81.0 58.0 04:25:59 INFO - PROCESS | 1662 | 04:25:59 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:77.50 stddev:10.62 (13.7%) stddev-sans-first:10.98 04:25:59 INFO - PROCESS | 1662 | Values: 84.0 71.0 91.0 70.0 69.0 69.0 90.0 67.0 69.0 92.0 04:25:59 INFO - PROCESS | 1662 | 04:25:59 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:97.20 Median:88.00 stddev:14.53 (16.5%) stddev-sans-first:13.53 04:25:59 INFO - PROCESS | 1662 | Values: 117.0 129.0 102.0 88.0 91.0 88.0 91.0 88.0 91.0 87.0 04:25:59 INFO - PROCESS | 1662 | 04:25:59 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:100.90 Median:101.00 stddev:1.29 (1.3%) stddev-sans-first:1.32 04:25:59 INFO - PROCESS | 1662 | Values: 100.0 100.0 101.0 103.0 101.0 99.0 101.0 103.0 101.0 100.0 04:25:59 INFO - PROCESS | 1662 | 04:25:59 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:82.80 Median:83.00 stddev:0.92 (1.1%) stddev-sans-first:0.71 04:25:59 INFO - PROCESS | 1662 | Values: 81.0 82.0 82.0 83.0 83.0 83.0 84.0 84.0 83.0 83.0 04:25:59 INFO - PROCESS | 1662 | 04:25:59 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:1.07 (2.0%) stddev-sans-first:0.97 04:25:59 INFO - PROCESS | 1662 | Values: 56.0 53.0 53.0 53.0 55.0 55.0 55.0 54.0 55.0 55.0 04:25:59 INFO - PROCESS | 1662 | 04:25:59 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:79.80 Median:70.00 stddev:17.43 (24.9%) stddev-sans-first:17.64 04:25:59 INFO - PROCESS | 1662 | Values: 65.0 70.0 99.0 110.0 105.0 69.0 70.0 71.0 69.0 70.0 04:25:59 INFO - PROCESS | 1662 | 04:25:59 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:89.00 Median:89.00 stddev:2.91 (3.3%) stddev-sans-first:2.99 04:25:59 INFO - PROCESS | 1662 | Values: 91.0 86.0 87.0 90.0 87.0 87.0 88.0 87.0 92.0 95.0 04:25:59 INFO - PROCESS | 1662 | 04:25:59 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:154.90 Median:152.00 stddev:12.57 (8.3%) stddev-sans-first:13.15 04:25:59 INFO - PROCESS | 1662 | Values: 149.0 149.0 151.0 157.0 148.0 148.0 158.0 153.0 189.0 147.0 04:25:59 INFO - PROCESS | 1662 | 04:25:59 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.10 Median:174.00 stddev:3.31 (1.9%) stddev-sans-first:3.52 04:25:59 INFO - PROCESS | 1662 | Values: 173.0 177.0 176.0 165.0 173.0 172.0 175.0 173.0 175.0 172.0 04:25:59 INFO - PROCESS | 1662 | 04:25:59 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 04:25:59 INFO - PROCESS | 1662 | Values: 67.0 68.0 67.0 66.0 67.0 69.0 67.0 67.0 66.0 67.0 04:25:59 INFO - PROCESS | 1662 | -------- Summary: end -------- 04:25:59 INFO - PROCESS | 1662 | 04:26:00 INFO - TEST-INFO | 1662: exit 0 04:26:00 INFO - TEST-OK | kraken | took 59507ms 04:26:00 INFO - SUITE-END | took 1179s 04:26:00 INFO - Completed test suite (00:19:40) 04:26:00 INFO - javascript benchmark 04:26:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3272.8036317820915, 3304.7851889627173, 3305.570275864138, 3318.2368055347283, 3332.4168270552645, 5506.889862202754, 5511.834644960652, 5597.552195824334, 5633.887322253558, 5643.548516118711, 4660.207764680004, 4676.649251306158, 5561.805336813212, 5622.32532096148, 5689.800856969997, 5055.317532133152, 5056.544910958013, 5096.847094587169, 5137.178051511759, 5174.663646862942, 3855.383138697809, 5205.141151709963, 5216.113260745663, 5217.243499692561, 5246.658967167122, 5060.114479966028, 5075.340205773263, 5094.79620815167, 5162.354705661811, 5175.974120129413, 5094.7197904115255, 5106.872328191787, 5126.307948426952, 5148.304978827885, 5156.20078887773, 5106.872328191787, 5107.61692873036, 5148.021875843615, 5148.073346797575, 5176.094183517869, 5499.285092937933, 5532.861678458031, 5580.720963951786, 5593.636413633141, 5613.410591887834, 6151.415615516519, 6151.661658608775, 6161.784337548209, 6166.599171053867, 6179.19670442844, 6152.723127459275, 6157.846053848645, 6214.471769899564, 6220.595661282002, 6226.2839773426185, 5567.331920169565, 5587.161925711176, 5588.189712491666, 5664.291963504562, 5747.856303592402, 6235.501159907197, 6248.4376406123665, 6255.718492667841, 6263.467605253604, 6265.843353916189, 6000.639961602318, 6013.398660133952, 6017.608855424383, 6030.500223535822, 6075.696215189223, 6088.786892458743, 6094.481969032682, 6117.143599896018, 6183.319834818167, 6185.319614842364, 6041.063635136561, 6077.36187700294, 6086.695665216722, 6093.025115981422, 6097.054956481752, 6097.433938678448, 6121.938780612226, 6126.509879209652, 6132.41742034506, 6140.17107690465, 4847.418309802846, 4878.658493905393, 4884.169691152512, 4889.266610008456, 4892.3884514435695, 4298.677599180011, 4307.741535507879, 4309.612134907873, 4316.1583491219535, 4317.050248945227, 5622.522085622768, 5647.915281270784, 5650.3219613646625, 5650.915236271459, 5660.320761508646, 6102.640737559575, 6104.004449388181, 6116.761878222105, 6136.365905528278, 6139.277491147187, 5619.297587801525, 5634.802781902614, 5635.436456354414, 5664.660120392789, 5678.801241956512, 5294.894102117936, 5320.441353657888, 5320.547753440924, 5328.92006619901, 5364.6512976656395, 6225.782097626561, 6235.407636274546, 6240.063990401476, 6252.718627661766, 6278.968605156945, 6126.945200426759, 6162.0, 6174.845628859314, 6176.752929882754, 6180.783672571439, 6143.109249158936, 6259.906101408392, 6264.467520260647, 6282.403171698681, 6297.149884765498, 5874.148248504029, 5879.529637628894, 5885.264341957255, 6072.635641861502, 6072.939270607238, 6088.238970128734, 6096.359882212481, 6104.725287362168, 6108.053251745987, 6108.297545782114, 4852.514748525119, 4862.82980095695, 5491.0116179088145, 5804.361520232686, 5832.096025116114, 5843.065109582446, 5880.911786323123, 5908.940910591011, 5910.202122713551, 5911.881762364815, 5737.540996720169, 5744.798932037525, 5752.07966725322, 5753.453421924911, 5758.856028599151, 5100.515451032146, 5836.970815145897, 5863.677497737665, 5987.34139244674, 6013.128096425906, 5858.502027327725, 5881.794137205177, 5894.823155305347, 5897.351291357862, 5899.203607512931], "value": 5565.200305253808, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.7210945542129, 480.86535769984374, 508.43055777529173, 509.13193005924893, 537.3202898333611, 37622.0, 39031.80484097579, 39390.60609393909, 39547.406788898115, 41517.0, 40784.79607601965, 41383.965400864916, 41452.75641730753, 41456.0, 41460.37809432861, 8309.584520773968, 9183.311251656136, 9347.205487533567, 9366.391184572982, 9373.437593744364, 8407.075221725605, 11009.779804403906, 11286.604968826092, 11347.773044539104, 11380.089592832554, 11294.491747871365, 11361.545538178463, 11399.48702308398, 11401.714957126096, 11404.201705880592, 8378.287845533076, 8597.054324024351, 11224.831627525593, 11332.6033588824, 11342.979131878088, 8405.915940840636, 8537.871931921027, 8591.914080859236, 8606.397552171351, 11246.831297530543, 39247.822565323084, 39557.20885582301, 39562.0, 39593.20813583712, 39604.80197599022, 3276.311974485361, 3277.4428347181038, 3297.159224397787, 3299.4390953537954, 3538.9115272118147, 25497.872510637328, 29464.705352946625, 29718.0, 30144.94492692767, 30733.846330768425, 3124.1626390639276, 3148.7166155045925, 3164.2247649326064, 3231.676832316773, 3244.4646633305474, 450278.0, 494790.052099478, 502978.0, 505483.0, 507404.0, 48748.26877596839, 50986.62667166604, 50987.90120987871, 51023.74488127536, 56586.585335366944, 53448.19827702588, 59767.103493447634, 60811.39188608146, 60973.08540371898, 64415.03377449342, 15299.61750956235, 15323.923380383027, 15525.767113493306, 15546.76679849803, 15551.22243887801, 9917.851232231522, 10749.936268163536, 10946.945265273782, 10967.341959482457, 11932.761344773056, 15978.60053498672, 16083.758743618855, 16326.510204693877, 16516.42192523256, 16769.91615041917, 7174.8206294842, 7176.677049532784, 7177.4258059355125, 7229.530080544748, 7237.095363079615, 785.6346798738614, 902.3548163063441, 980.9068138526828, 1017.9338343007681, 1055.4458909072798, 27741.861290693418, 28677.713222867922, 28962.27594310159, 29718.0, 29766.851165744032, 983.8783786483415, 1018.8624535687735, 1050.6427814543092, 1055.1137044882337, 1107.6234080412698, 14690.632734181518, 15008.549743507712, 15153.621159471102, 15185.760912485175, 15364.462243821412, 637273.0, 639327.0, 642065.0, 643150.0, 644391.0, 56562.71718641381, 56586.0, 56706.71646641823, 56738.7163064182, 56799.58001050007, 1216.2945491614842, 1252.4426630149496, 1254.5420921363818, 1340.671535473815, 1359.2252416122913, 26760.866195668896, 26860.05989790348, 26874.59688104719, 30901.8454907724, 31628.05115846528, 52295.95408091893, 53130.749540037476, 53160.93678126339, 53250.0, 53304.46695532995, 536690.0, 541442.2206778665, 543309.0, 543312.2834385802, 543636.0, 31692.366152677278, 32447.0, 33720.49419258664, 37791.59875611208, 38062.85811425662, 36141.27717445689, 44564.33153502635, 45382.546174537834, 45535.0, 45775.77112114418, 30027.399452011276, 33610.159746006546, 36291.45562816507, 37477.81261093677, 37656.43515347327, 34502.0, 37418.62581374151, 37765.4335184978, 38206.61793382142, 38297.808510957264, 36808.81595592005, 36962.0, 37512.437313439776, 37566.0, 37601.62398375981, 26595.601065984414, 32119.357612848078, 32432.67567324391, 33821.98534043982, 33908.83045584855, 21105.89447052755, 24428.022879084638, 24648.630270545596, 25169.37076573027, 25474.363140921625], "value": 19525.76005629203, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.3746194312178, 365.8280608114186, 365.86828741653, 379.2471943192763, 402.71809733186774, 13728.519501817438, 15109.168995705231, 19576.804231957674, 19607.0, 20593.794062059376, 19335.806641933577, 19890.502737431572, 19891.005449727527, 20098.799012009913, 20164.39506814798, 5086.46592107828, 5393.541548968323, 5465.0, 5481.342238931334, 5497.505224529791, 5422.132458806592, 5464.12573988162, 5629.887402251952, 5957.910631340534, 6004.54965877561, 6070.119832624267, 6072.51419886408, 6073.453389194971, 6087.421694938974, 6094.207752992105, 5799.391063938289, 5981.49157321626, 6053.667048312341, 6057.606255593417, 6060.939390606081, 5016.398032236155, 5827.737751801179, 5835.883282334372, 5838.883222335529, 5840.503557197643, 12194.878051219463, 12758.872411275861, 12764.234145951179, 12771.0, 12788.936055319757, 2044.8875311857842, 2139.4330502416874, 2222.9555408891893, 2481.3796550862285, 2584.1988983415204, 9559.330846840723, 9714.465704386255, 9788.853167202498, 10138.847917281246, 10273.12678422335, 1566.4517418903406, 1769.9557511062326, 1798.6492633936257, 1816.9636607267912, 1896.0993528074055, 88006.67989980092, 99782.002179978, 101204.0, 117442.82557174405, 119636.0, 11053.502592383202, 11158.497867595977, 11236.775264494665, 11261.155413343868, 11406.657800266004, 12281.754364912651, 13020.414081366362, 13032.869671303166, 13193.868061319265, 14074.085184462978, 8099.149589293157, 8153.0, 8236.546989915492, 8331.916680833236, 8377.329813614897, 6612.206535215709, 8026.0, 8041.718539851077, 8177.9591102044105, 8187.877181842277, 4373.737575745466, 4479.731216126977, 4487.0929854811575, 4547.113312903952, 4556.589906908394, 1850.2783914273443, 1896.3173257627243, 1900.5818719881604, 1901.5911579010578, 1908.0251939242817, 816.2123550773532, 881.2331275792124, 893.8078313162702, 931.9050116113675, 951.6145960885852, 17314.480565583053, 18228.0, 20686.896565517076, 20699.58600827975, 20712.58574828495, 790.075611534506, 794.5074054086504, 933.9906600934039, 951.5480146930153, 961.1301771896445, 10055.849162262572, 10733.749269447659, 10766.649791040085, 10831.133509319236, 10880.0, 516510.0, 541538.0, 560041.0, 566939.3306066969, 570663.0, 128406.0, 128409.35795320963, 131650.34174829064, 132382.01426978604, 138794.0, 961.4423634292094, 991.9900800991898, 1010.8231059564547, 1011.9241056920907, 1013.4071568132578, 18335.90832045831, 19134.90432547884, 19259.51851203731, 19289.324873629357, 19346.4196074118, 54799.0, 54898.0, 56269.0, 56452.0, 57638.847223054465, 67329.66335168293, 80127.19872801198, 82115.35769284461, 82678.58660706658, 84510.57744711237, 7927.801804954922, 7962.601869906597, 8005.119436862057, 8115.391345648982, 8235.588220589067, 7444.553326800409, 12376.381180940736, 12641.747165056831, 12704.491820327083, 13115.672108197372, 10102.14131798776, 10856.294340867818, 11023.283486573348, 11193.328400296008, 11436.256643318158, 10630.946845265724, 10684.0, 10730.356178629307, 11059.61291354831, 11286.66140015801, 11176.944115279372, 11219.439028048402, 11292.588864861738, 11351.943240284076, 11528.365939873387, 7897.802554935943, 7929.444938854455, 7975.72084977023, 7990.200979902196, 8025.638846252049, 8099.797505062421, 9627.759306017126, 11025.338479691245, 11187.216894817284, 11210.383428911487], "value": 9128.715378321598, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1325.2644782145906, 1380.1650001748942, 1473.8378778334384, 1641.9096949667764, 1849.3342396737173, 7107.680154393051, 7209.819754506133, 7454.440916931231, 8252.257296843298, 8269.875951860728, 8159.91840081599, 8246.422750407473, 8246.422750407473, 8255.133211012831, 8256.339492840574, 2277.1005452846116, 2315.536892621474, 2317.80298674612, 2319.8724070176136, 2320.9419764505856, 2242.3048854855047, 2289.175896677195, 2296.9310920672406, 2302.4934514406805, 2303.239930822833, 2265.660150977358, 2266.9433264168365, 2305.101010605865, 2306.7116610423695, 2308.1921327535397, 2204.415829805103, 2208.116753298685, 2232.0960011195493, 2232.229880691167, 2232.9888350558304, 1973.6250112478583, 2286.119843860126, 2307.4462129089065, 2308.676785250066, 2314.722233331994, 8227.958860205721, 8290.129536398652, 8298.8340233195, 8311.044229913578, 8311.792205194857, 4977.875553111165, 4997.225430058346, 5166.199239117943, 5167.534921857051, 5168.8966220675375, 3346.498025296201, 3497.055794935379, 3522.7357948153735, 3603.4774957631257, 3605.0086226287717, 1020.7703266764919, 1053.8893416191343, 1085.978280434387, 1087.1791797193148, 1095.895889890459, 9345.92521859988, 10683.732906677395, 10797.136229101652, 10981.0, 10987.285826421337, 4167.333226683716, 4374.147041326974, 4429.11417716458, 4431.468223813163, 4450.866474005785, 7345.485815992829, 8632.611532481053, 8644.91355086441, 8655.826883462296, 8661.30709543235, 4941.0, 5047.470015648304, 5049.065922804293, 5050.242463630485, 5054.115529782273, 4249.213895429293, 4392.516823149451, 4402.141582391467, 4681.485036645966, 4733.408323959505, 3505.1762835733584, 3572.1426857554, 3635.527381440396, 3665.4685070664605, 3672.192117734094, 1127.1658972360558, 1128.8871112888646, 1133.557912414159, 1135.9716007099723, 1141.303804679145, 805.5327909812295, 813.6297984417076, 831.9168083191632, 866.5147517390282, 882.3426547222232, 5306.814261500829, 5591.692456914909, 5759.22250496186, 5775.509081728016, 5812.360640329561, 857.4169564696067, 882.5366682491743, 882.849915514364, 893.647009431284, 898.6046139698511, 3253.5933008373954, 3267.7909173605917, 3675.320065787784, 3720.1257704439436, 3836.462895194675, 9869.851952220723, 10051.547680354255, 10081.848772268422, 10247.795044099077, 10401.063904248502, 10321.277510574339, 10332.638357657446, 10340.948295258475, 10342.689719308282, 10360.948195258976, 7541.2458754124145, 7818.0, 7864.567448790257, 7929.0, 7963.76108716751, 4829.124440519076, 5584.832455026356, 5639.689817060101, 5685.403032681509, 5773.96004630292, 9361.578728957076, 9420.387674801115, 9464.763380915258, 9526.857097143687, 9625.470599116836, 9447.338686292169, 9542.52287385614, 9543.379680320757, 9567.9043209567, 9688.612455501701, 6226.25284965807, 6443.774467893789, 6461.321561236003, 6682.63245521501, 6864.759733409307, 7390.630468476662, 7727.381809455118, 7888.2506161914525, 7959.602019898866, 8074.3944204183745, 7076.964615176891, 7198.136223653195, 7232.0, 7272.745453909088, 7349.412047036331, 6978.406835419046, 6989.30106989297, 6990.580565165903, 7128.714851405885, 7148.0, 5678.659280443187, 6641.667916604054, 6697.229818570732, 6706.329367063255, 6714.9328506716265, 4772.928406073842, 4806.687565308243, 4821.373221481325, 4831.06757297084, 4955.108080545537, 4787.521247875185, 4874.512548745097, 4899.16714158601, 4917.213245880642, 4934.703917764946], "value": 4327.40127800043, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.3642167424786, 299.06542056074767, 303.4053255618986, 309.64236307065346, 313.36856234687093, 5088.905885234673, 5179.585633149349, 5231.142944254787, 5251.448597897213, 5252.52727254547, 4355.04189078403, 4893.29047288143, 4962.578180854623, 5027.2710456983805, 5078.9746051269785, 3730.996547779037, 3939.6559047366773, 3942.8619998300064, 3971.5035620547433, 3988.421678856564, 2971.2274808549823, 3316.121227874615, 3806.7437745544044, 4030.455888455065, 4033.7378070425475, 3914.7651140931494, 3936.3308237599676, 3959.8220978461873, 3982.283189025974, 3988.544181373792, 3320.7509436792216, 3366.074329559366, 3859.247446747885, 3980.1641655252433, 3983.3825757007567, 3475.165960169541, 3487.0, 3509.596396414394, 3512.964870351315, 3514.5255390522207, 4803.615710743132, 4958.677685950402, 5008.974955125237, 5018.949810501885, 5026.773795179189, 3805.486259354979, 3987.112565248888, 4446.866594002185, 4523.411956445674, 4566.520515345907, 4704.741723972513, 4872.344572208155, 5322.680639161663, 5424.969255841377, 5428.511433970962, 3826.2730081284467, 3843.2121415110114, 3846.307664620367, 3846.4614953906475, 3848.8845334640005, 4736.384270044872, 5275.947240527584, 5659.717014149276, 5663.461971112738, 5676.971615141939, 3174.555562221291, 3500.579930408367, 3766.322062028861, 3786.924261514754, 3799.430085487199, 3841.635044670752, 3845.9038524036564, 3860.2086572253183, 3873.24471728016, 3873.3996230584303, 3614.4397618369194, 4454.7549884756, 4505.166544189336, 4528.388667529907, 4538.29656403258, 5385.569154467633, 5475.521609165503, 5478.657728856456, 5495.230667706524, 5509.393966663663, 1046.7958748044211, 1073.436445865911, 1124.4490199802037, 1227.8096894981293, 1228.2129501575187, 661.7055410342448, 717.9338682057141, 775.0350813237555, 788.9723859664884, 801.5351096364095, 4196.3982330954395, 4239.04621460169, 4390.85837682205, 4544.681872268976, 4910.772306923269, 5547.805826796042, 5619.719014049363, 5630.662160270397, 5653.152027195953, 5661.443103146586, 4438.356438316426, 4600.090962250695, 4885.951140488621, 5103.234514822732, 5130.717810520383, 2603.71116297434, 2622.1871219921886, 2755.600437936488, 2782.4296019315993, 2801.9159425217276, 4565.0641618468135, 4582.15099316569, 4748.430188377348, 5371.093261892051, 5630.6058575900115, 4141.6065473779945, 4284.485861696617, 5061.44324124346, 5362.785488580491, 5514.972425137928, 4778.498257682893, 5027.265593370282, 5325.653832500875, 5503.614746967673, 5579.46995035471, 4409.382686423839, 4969.602431805519, 5278.947210527999, 5287.629865909349, 5325.786968521215, 4643.8374656886845, 4772.665913386029, 5534.695591742332, 5580.632744977506, 5614.69119198448, 5141.38303403594, 5872.265966754156, 5883.499902508163, 5891.675957822361, 5900.675462849585, 3710.9940947832342, 3788.2156614888586, 3824.4263360496147, 3850.210706805154, 3856.382978723391, 4926.852194434173, 5369.892602148013, 5595.272614560081, 5643.576731745217, 5671.914921276267, 5483.643563168382, 5486.698231597141, 5493.23094766741, 5511.944880551143, 5513.4589737313745, 4578.070035177422, 4810.542998415145, 5240.554552863049, 5456.236126942152, 5511.409637470382, 4665.393498845128, 5150.819721309736, 5278.366596008504, 5337.359516858002, 5372.677639341652, 4964.925526117039, 5180.6891586503425, 5423.62034657586, 5484.698341591251, 5525.309336332958, 5396.163594642837, 5397.596624877481, 5401.0, 5407.297051383295, 5448.182772584144], "value": 3900.8970061353884, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [682.5631595778701, 732.103173612325, 768.9961550192248, 770.907491101068, 775.5153029356652, 13687.24720140392, 14062.226577538231, 14507.782383264259, 14826.777598336033, 14910.925445372757, 13092.672683182902, 13485.865141348584, 13497.122687025361, 13499.66250843727, 13513.121647092932, 808.4219782855272, 820.3478234803346, 832.7876391520153, 842.6334544473174, 844.1937949258444, 656.8193746719643, 672.6569449580725, 673.4006734006714, 835.6448509383519, 835.8537255980209, 704.9083619129506, 708.2174197511753, 708.6492186367756, 843.3337663246028, 863.2403484933282, 869.4739682492096, 869.8042940338437, 871.1984973824098, 871.6905498548051, 871.7951281448886, 578.973946172419, 642.383312020461, 644.8065580325883, 644.9774257900998, 645.9806205813833, 11365.772684546346, 11818.586349477728, 13529.0, 13899.235542045184, 13935.651608709763, 12872.485100595964, 13232.735345293137, 13288.26914519701, 13412.0, 13537.729245415037, 11043.72390690231, 11418.42907854604, 11534.192606517547, 11624.767504649944, 11731.0, 3618.0412190769466, 3619.348517266891, 3643.544556930384, 3699.5005674233903, 3766.0773110587807, 89065.0, 92433.53783231108, 92503.0, 92657.0, 94390.52804736001, 10056.34633748804, 10099.747506312255, 10290.691279261635, 10452.581896724047, 10722.03501684852, 18706.71939920902, 18859.811401885807, 18963.62072758537, 19135.617287654168, 20280.391588252376, 7414.14737305206, 7536.321731044232, 7673.884891726629, 7682.7311044113185, 7836.804079898048, 4137.2552940471005, 4205.432266643964, 4219.198352313051, 4241.384999175102, 4369.016971181522, 2845.701201373867, 3146.496560550301, 3303.174206448388, 3323.3519463704347, 3356.865725370958, 657.1260223902199, 667.5794249622707, 674.1674032569772, 677.3362105136994, 679.7077256779633, 506.2684421011603, 642.8521440068716, 648.9480841532666, 657.9638119903451, 675.3482889012029, 7609.771706848804, 7855.450118491765, 8539.100273803726, 8540.914590854012, 8960.059193784558, 572.461885827321, 612.6936531734133, 615.6244690738646, 642.6588395389209, 658.1312667279241, 4198.853040143641, 4264.466941632296, 4461.0, 4483.574060464251, 4525.842741413124, 90766.0, 110207.0, 111841.88158118314, 116522.0, 117531.0, 65766.0, 70962.29037709557, 88096.559517202, 89766.55116724374, 98863.0, 73093.90359144617, 84023.0, 86942.56528717316, 87791.0, 88602.55698721594, 8480.32157427392, 8506.234438900528, 8556.962278391082, 8710.69512567057, 8817.955910220408, 37722.0, 40663.39004914869, 43341.13317733564, 49720.005599887074, 54977.1753423691, 60546.69726651339, 80319.59840200996, 80776.59611701904, 80915.78626320728, 81926.59036704779, 11077.778444430905, 11095.167862410513, 11248.94375528117, 11491.712707182387, 11617.361045142261, 11901.583444579399, 12053.397330133634, 12229.510819567118, 12354.0, 12602.810957835824, 11673.533058677898, 12022.038236941198, 12031.0, 12109.939450302692, 12197.69505762363, 11415.885841141815, 11770.17608767378, 11871.406429678655, 11983.28100313984, 12013.939930300643, 11426.314421134759, 11755.588554400554, 11814.645560633195, 11869.940650296694, 11954.76090478203, 9503.334766566273, 9627.546389298252, 9851.556679949275, 9882.555285012048, 10038.849417258893, 10691.946540267249, 10943.671689849318, 11075.944620276847, 11319.660410187707, 11373.0], "value": 7007.156870308581, "unit": "score"}], "name": "dromaeo_css", "value": 6997.026218960329, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 113.0, 114.0, 113.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 109.0, 113.0, 149.0, 109.0, 106.0, 112.0, 99.0, 163.0, 107.0], "value": 121.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 184.0, 183.0, 182.0, 185.0, 181.0, 181.0, 183.0, 183.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 61.0, 59.0, 88.0, 65.0, 59.0, 58.0, 59.0, 81.0, 58.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 91.0, 70.0, 69.0, 69.0, 90.0, 67.0, 69.0, 92.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 129.0, 102.0, 88.0, 91.0, 88.0, 91.0, 88.0, 91.0, 87.0], "value": 97.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 100.0, 101.0, 103.0, 101.0, 99.0, 101.0, 103.0, 101.0, 100.0], "value": 100.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 82.0, 83.0, 83.0, 83.0, 84.0, 84.0, 83.0, 83.0], "value": 82.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 53.0, 53.0, 55.0, 55.0, 55.0, 54.0, 55.0, 55.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 99.0, 110.0, 105.0, 69.0, 70.0, 71.0, 69.0, 70.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 86.0, 87.0, 90.0, 87.0, 87.0, 88.0, 87.0, 92.0, 95.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 151.0, 157.0, 148.0, 148.0, 158.0, 153.0, 189.0, 147.0], "value": 154.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 177.0, 176.0, 165.0, 173.0, 172.0, 175.0, 173.0, 175.0, 172.0], "value": 173.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 66.0, 67.0, 69.0, 67.0, 67.0, 66.0, 67.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1459.3999999999999, "alertThreshold": 2.0}]} 04:26:00 INFO - Return code: 0 04:26:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:26:00 INFO - # TBPL SUCCESS # 04:26:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:26:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:26:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:26:00 INFO - 2016-11-14 04:26:00.600 screenresolution[1666:9881] starting screenresolution argv=screenresolution get 04:26:00 INFO - 2016-11-14 04:26:00.604 screenresolution[1666:9881] Display 0: 1600x1200x32@60 04:26:00 INFO - 2016-11-14 04:26:00.610 screenresolution[1667:9884] starting screenresolution argv=screenresolution list 04:26:00 INFO - Available Modes on Display 0 04:26:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:26:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:26:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:26:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:26:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:26:00 INFO - Intel Iris: 04:26:00 INFO - Chipset Model: Intel Iris 04:26:00 INFO - Type: GPU 04:26:00 INFO - Bus: Built-In 04:26:00 INFO - VRAM (Dynamic, Max): 1536 MB 04:26:00 INFO - Vendor: Intel (0x8086) 04:26:00 INFO - Device ID: 0x0a2e 04:26:00 INFO - Revision ID: 0x0009 04:26:00 INFO - Displays: 04:26:00 INFO - Display: 04:26:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:26:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:26:00 INFO - Display Serial Number: 1600x1200 60 04:26:00 INFO - Main Display: Yes 04:26:00 INFO - Mirror: Off 04:26:00 INFO - Online: Yes 04:26:00 INFO - Rotation: Supported 04:26:00 INFO - Return code: 0 04:26:00 INFO - Running post-action listener: _resource_record_post_action 04:26:00 INFO - [mozharness: 2016-11-14 12:26:00.847057Z] Finished run-tests step (success) 04:26:00 INFO - Running post-run listener: _resource_record_post_run 04:26:00 INFO - Total resource usage - Wall time: 1215s; CPU: 53.0%; Read bytes: 38892544; Write bytes: 699114496; Read time: 387; Write time: 1880 04:26:00 INFO - TinderboxPrint: CPU usage
53.4% 04:26:00 INFO - TinderboxPrint: I/O read bytes / time
38,892,544 / 387 04:26:00 INFO - TinderboxPrint: I/O write bytes / time
699,114,496 / 1,880 04:26:00 INFO - TinderboxPrint: CPU idle
2,266.3 (46.6%) 04:26:00 INFO - TinderboxPrint: CPU system
969.4 (19.9%) 04:26:00 INFO - TinderboxPrint: CPU user
1,626.0 (33.4%) 04:26:00 INFO - TinderboxPrint: Swap in / out
437,219,328 / 0 04:26:00 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 384629248; Write bytes: 374941696; Read time: 29276; Write time: 738 04:26:00 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 32640000; Write bytes: 296315904; Read time: 308; Write time: 1086 04:26:01 INFO - Running post-run listener: _upload_blobber_files 04:26:01 INFO - Blob upload gear active. 04:26:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:26:01 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 04:26:01 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'] 04:26:01 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 04:26:01 INFO - (blobuploader) - INFO - Open directory for files ... 04:26:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:26:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:26:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:26:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:26:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:26:02 INFO - (blobuploader) - INFO - Done attempting. 04:26:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:26:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:26:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:26:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:26:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:26:03 INFO - (blobuploader) - INFO - Done attempting. 04:26:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:26:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:26:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:26:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:26:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:26:06 INFO - (blobuploader) - INFO - Done attempting. 04:26:06 INFO - (blobuploader) - INFO - Iteration through files over. 04:26:06 INFO - Return code: 0 04:26:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:26:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:26:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/623691562a5f8c6161203301a7ecadbd70658a4fe0959fae302dabd3b8511cdbf8effabc96024de15e5c90e8e5fd7281a3bdefa8a2c4ee1daccfbf59d741166d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/387163ec56a7df43ed93d137b3a92b6345a2b8ebe657a62edb5f12ad688b8fd5efc318335afd55ed82ec80b565ab8392953915a9950627e0784dca154ff11d9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ce25444ba46ff22e822281497cf9397beb9b4c3c56eacc01dd5859c64d2cafbe1d0e5e96507d191f2c3242be3386a95b40c35d1013f5067fc6cddcf1bc69a73"} 04:26:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:26:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:26:06 INFO - Contents: 04:26:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/623691562a5f8c6161203301a7ecadbd70658a4fe0959fae302dabd3b8511cdbf8effabc96024de15e5c90e8e5fd7281a3bdefa8a2c4ee1daccfbf59d741166d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/387163ec56a7df43ed93d137b3a92b6345a2b8ebe657a62edb5f12ad688b8fd5efc318335afd55ed82ec80b565ab8392953915a9950627e0784dca154ff11d9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ce25444ba46ff22e822281497cf9397beb9b4c3c56eacc01dd5859c64d2cafbe1d0e5e96507d191f2c3242be3386a95b40c35d1013f5067fc6cddcf1bc69a73"} 04:26:06 INFO - Running post-run listener: copy_logs_to_upload_dir 04:26:06 INFO - Copying logs to upload dir... 04:26:06 INFO - mkdir: /builds/slave/test/build/upload/logs 04:26:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.089911 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-14 04:26:06.242528) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 04:26:06.246724) ========= 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.MzRdiwLXWq/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.Ne7TrzGqhs/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/623691562a5f8c6161203301a7ecadbd70658a4fe0959fae302dabd3b8511cdbf8effabc96024de15e5c90e8e5fd7281a3bdefa8a2c4ee1daccfbf59d741166d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/387163ec56a7df43ed93d137b3a92b6345a2b8ebe657a62edb5f12ad688b8fd5efc318335afd55ed82ec80b565ab8392953915a9950627e0784dca154ff11d9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ce25444ba46ff22e822281497cf9397beb9b4c3c56eacc01dd5859c64d2cafbe1d0e5e96507d191f2c3242be3386a95b40c35d1013f5067fc6cddcf1bc69a73"} build_url:https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011071 build_url: 'https://queue.taskcluster.net/v1/task/CYAir7gyQOS0uHKOI0vksg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/623691562a5f8c6161203301a7ecadbd70658a4fe0959fae302dabd3b8511cdbf8effabc96024de15e5c90e8e5fd7281a3bdefa8a2c4ee1daccfbf59d741166d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/387163ec56a7df43ed93d137b3a92b6345a2b8ebe657a62edb5f12ad688b8fd5efc318335afd55ed82ec80b565ab8392953915a9950627e0784dca154ff11d9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ce25444ba46ff22e822281497cf9397beb9b4c3c56eacc01dd5859c64d2cafbe1d0e5e96507d191f2c3242be3386a95b40c35d1013f5067fc6cddcf1bc69a73"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 04:26:06.280955) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 04:26:06.281288) ========= 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.MzRdiwLXWq/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.Ne7TrzGqhs/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.005171 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 04:26:06.330113) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 04:26:06.330427) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 04:26:06.330765) ========= ========= Total master_lag: 2.12 =========