builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0111 starttime: 1474633496.9 results: success (0) buildid: 20160923030856 builduid: b4198cba392140c7b7045f88d811efcf revision: 9eae36f83dbae7434e74cd9c689a965031778370 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-23 05:24:56.900531) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-23 05:24:56.900944) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-23 05:24:56.901395) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-23 05:24:56.932088) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 05:24:56.932446) ========= 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.jY0SPY2xlp/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.ZQ71r3EIeA/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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-09-23 05:24:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.97M=0.001s 2016-09-23 05:24:57 (9.97 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.103098 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 05:24:57.058399) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 05:24:57.058678) ========= 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.jY0SPY2xlp/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.ZQ71r3EIeA/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.028276 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 05:24:57.107435) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-23 05:24:57.107774) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9eae36f83dbae7434e74cd9c689a965031778370 --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 9eae36f83dbae7434e74cd9c689a965031778370 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jY0SPY2xlp/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.ZQ71r3EIeA/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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-09-23 05:24:57,184 truncating revision to first 12 chars 2016-09-23 05:24:57,184 Setting DEBUG logging. 2016-09-23 05:24:57,184 attempt 1/10 2016-09-23 05:24:57,184 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9eae36f83dba?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-23 05:24:58,444 unpacking tar archive at: mozilla-central-9eae36f83dba/testing/mozharness/ program finished with exit code 0 elapsedTime=1.518684 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-23 05:24:58.655156) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-23 05:24:58.655958) ========= script_repo_revision: 9eae36f83dbae7434e74cd9c689a965031778370 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-23 05:24:58.656851) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-23 05:24:58.657490) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-23 05:24:58.678505) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-23 05:24:58.679219) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jY0SPY2xlp/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.ZQ71r3EIeA/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:24:58 INFO - MultiFileLogger online at 20160923 05:24:58 in /builds/slave/test 05:24:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:24:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:24:58 INFO - {'append_to_log': False, 05:24:58 INFO - 'base_work_dir': '/builds/slave/test', 05:24:58 INFO - 'blob_upload_branch': 'Firefox', 05:24:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:24:58 INFO - 'branch': 'Firefox', 05:24:58 INFO - 'buildbot_json_path': 'buildprops.json', 05:24:58 INFO - 'config_files': ('talos/mac_config.py',), 05:24:58 INFO - 'default_actions': ('clobber', 05:24:58 INFO - 'read-buildbot-config', 05:24:58 INFO - 'download-and-extract', 05:24:58 INFO - 'populate-webroot', 05:24:58 INFO - 'create-virtualenv', 05:24:58 INFO - 'install', 05:24:58 INFO - 'run-tests'), 05:24:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:24:58 INFO - 'download_minidump_stackwalk': True, 05:24:58 INFO - 'download_symbols': 'ondemand', 05:24:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:24:58 INFO - 'tooltool.py': '/tools/tooltool.py', 05:24:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:24:58 INFO - '/tools/misc-python/virtualenv.py')}, 05:24:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:24:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:24:58 INFO - 'installer_path': 'installer.exe', 05:24:58 INFO - 'log_level': 'info', 05:24:58 INFO - 'log_name': 'talos', 05:24:58 INFO - 'log_to_console': True, 05:24:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:24:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:24:58 INFO - 'opt_config_files': (), 05:24:58 INFO - 'pip_index': False, 05:24:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:24:58 INFO - 'cmd': ('bash', 05:24:58 INFO - '-c', 05:24:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:24:58 INFO - 'enabled': True, 05:24:58 INFO - 'halt_on_failure': False, 05:24:58 INFO - 'name': 'check_screen_resolution'},), 05:24:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:24:58 INFO - 'cmd': ('bash', 05:24:58 INFO - '-c', 05:24:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:24:58 INFO - 'enabled': True, 05:24:58 INFO - 'halt_on_failure': False, 05:24:58 INFO - 'name': 'check_screen_resolution'},), 05:24:58 INFO - 'run_cmd_checks_enabled': True, 05:24:58 INFO - 'sps_profile': False, 05:24:58 INFO - 'sps_profile_interval': 0, 05:24:58 INFO - 'suite': 'dromaeojs-e10s', 05:24:58 INFO - 'system_bits': '32', 05:24:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:24:58 INFO - 'title': 't-yosemite-r7-0111', 05:24:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:24:58 INFO - 'use_talos_json': True, 05:24:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:24:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:24:58 INFO - 'work_dir': 'build'} 05:24:58 INFO - [mozharness: 2016-09-23 12:24:58.875933Z] Running clobber step. 05:24:58 INFO - Running pre-action listener: _resource_record_pre_action 05:24:58 INFO - Running main action method: clobber 05:24:58 INFO - rmtree: /builds/slave/test/build 05:24:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:25:00 INFO - Running post-action listener: _resource_record_post_action 05:25:00 INFO - [mozharness: 2016-09-23 12:25:00.984014Z] Finished clobber step (success) 05:25:00 INFO - [mozharness: 2016-09-23 12:25:00.984136Z] Running read-buildbot-config step. 05:25:00 INFO - Running pre-action listener: _resource_record_pre_action 05:25:00 INFO - Running main action method: read_buildbot_config 05:25:00 INFO - Using buildbot properties: 05:25:00 INFO - { 05:25:00 INFO - "project": "", 05:25:00 INFO - "product": "firefox", 05:25:00 INFO - "script_repo_revision": "production", 05:25:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:25:00 INFO - "repository": "", 05:25:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:25:00 INFO - "buildid": "20160923030856", 05:25:00 INFO - "stage_platform": "macosx64", 05:25:00 INFO - "basedir": "/builds/slave/test", 05:25:00 INFO - "nightly_build": "True", 05:25:00 INFO - "buildnumber": 298, 05:25:00 INFO - "slavename": "t-yosemite-r7-0111", 05:25:00 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:25:00 INFO - "platform": "yosemite_r7", 05:25:00 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:25:00 INFO - "branch": "mozilla-central", 05:25:00 INFO - "repo_path": "mozilla-central", 05:25:00 INFO - "revision": "9eae36f83dbae7434e74cd9c689a965031778370", 05:25:00 INFO - "pgo_build": "False", 05:25:00 INFO - "builduid": "b4198cba392140c7b7045f88d811efcf", 05:25:00 INFO - "slavebuilddir": "test" 05:25:00 INFO - } 05:25:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:25:00 INFO - Running post-action listener: _resource_record_post_action 05:25:00 INFO - [mozharness: 2016-09-23 12:25:00.989231Z] Finished read-buildbot-config step (success) 05:25:00 INFO - [mozharness: 2016-09-23 12:25:00.989429Z] Running download-and-extract step. 05:25:00 INFO - Running pre-action listener: _resource_record_pre_action 05:25:00 INFO - Running main action method: download_and_extract 05:25:00 INFO - mkdir: /builds/slave/test/build/tests 05:25:00 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')]} 05:25:00 INFO - trying https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:25:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/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 05:25:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/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 05:25:02 INFO - Downloaded 1199 bytes. 05:25:02 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:25:02 INFO - Using the following test package requirements: 05:25:02 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:25:02 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:25:02 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:25:02 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:25:02 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:25:02 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:25:02 INFO - u'jsshell-mac.zip'], 05:25:02 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:25:02 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:25:02 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:25:02 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:25:02 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:25:02 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:25:02 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:25:02 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:25:02 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:25:02 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:25:02 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:25:02 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:25:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:25:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:25:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:25:02 INFO - Expected file size: 16438904 05:25:10 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 05:25:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:25:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:25:10 INFO - Fetch https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:25:10 INFO - Expected file size: 16438904 05:25:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:25:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:25:17 INFO - Fetch https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:25:18 INFO - Expected file size: 14414059 05:25:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:18 INFO - trying https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:25:18 INFO - Downloading https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:25:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/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 05:25:22 INFO - Downloaded 111574800 bytes. 05:25:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:25:22 INFO - mkdir: /builds/slave/test/properties 05:25:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:25:22 INFO - Writing to file /builds/slave/test/properties/build_url 05:25:22 INFO - Contents: 05:25:22 INFO - build_url:https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:25:22 INFO - Running post-action listener: _resource_record_post_action 05:25:22 INFO - Running post-action listener: set_extra_try_arguments 05:25:22 INFO - [mozharness: 2016-09-23 12:25:22.925470Z] Finished download-and-extract step (success) 05:25:22 INFO - [mozharness: 2016-09-23 12:25:22.925592Z] Running populate-webroot step. 05:25:22 INFO - Running pre-action listener: _resource_record_pre_action 05:25:22 INFO - Running main action method: populate_webroot 05:25:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:25:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:25:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:25:22 INFO - '--tppagecycles', 05:25:22 INFO - '7'], 05:25:22 INFO - 'tests': ['tsvgm']}}, 05:25:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:25:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:25:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:25:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:25:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:25:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:25:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:22 INFO - 'talos_options': ['--disable-e10s'], 05:25:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:25:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:25:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:22 INFO - 'talos_options': ['--disable-e10s'], 05:25:22 INFO - 'tests': ['damp', 'tps']}, 05:25:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:22 INFO - 'tests': ['damp', 'tps']}, 05:25:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:25:22 INFO - 'tests': ['dromaeo_dom']}, 05:25:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:25:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:25:22 INFO - 'tests': ['basic_compositor_video']}, 05:25:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:25:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:25:22 INFO - 'tests': ['a11yr', 05:25:22 INFO - 'ts_paint', 05:25:22 INFO - 'tpaint', 05:25:22 INFO - 'sessionrestore', 05:25:22 INFO - 'sessionrestore_no_auto_restore', 05:25:22 INFO - 'tabpaint']}, 05:25:22 INFO - 'other-e10s': {'tests': ['a11yr', 05:25:22 INFO - 'ts_paint', 05:25:22 INFO - 'tpaint', 05:25:22 INFO - 'sessionrestore', 05:25:22 INFO - 'sessionrestore_no_auto_restore', 05:25:22 INFO - 'tabpaint']}, 05:25:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:25:22 INFO - 'ts_paint', 05:25:22 INFO - 'tpaint', 05:25:22 INFO - 'sessionrestore', 05:25:22 INFO - 'sessionrestore_no_auto_restore', 05:25:22 INFO - 'tabpaint']}, 05:25:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:25:22 INFO - 'ts_paint', 05:25:22 INFO - 'tpaint', 05:25:22 INFO - 'sessionrestore', 05:25:22 INFO - 'sessionrestore_no_auto_restore', 05:25:22 INFO - 'tabpaint']}, 05:25:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:25:22 INFO - 'tests': ['a11yr', 05:25:22 INFO - 'ts_paint', 05:25:22 INFO - 'tpaint', 05:25:22 INFO - 'sessionrestore', 05:25:22 INFO - 'sessionrestore_no_auto_restore', 05:25:22 INFO - 'tabpaint']}, 05:25:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:25:22 INFO - 'tests': ['a11yr', 05:25:22 INFO - 'ts_paint', 05:25:22 INFO - 'tpaint', 05:25:22 INFO - 'sessionrestore', 05:25:22 INFO - 'sessionrestore_no_auto_restore', 05:25:22 INFO - 'tabpaint']}, 05:25:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:25:22 INFO - 'tests': ['tsvgx', 05:25:22 INFO - 'tsvgr_opacity', 05:25:22 INFO - 'tart', 05:25:22 INFO - 'tscrollx', 05:25:22 INFO - 'cart']}, 05:25:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:25:22 INFO - 'tsvgr_opacity', 05:25:22 INFO - 'tart', 05:25:22 INFO - 'tscrollx', 05:25:22 INFO - 'cart']}, 05:25:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:22 INFO - 'talos_options': ['--disable-e10s'], 05:25:22 INFO - 'tests': ['tp5o']}, 05:25:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:22 INFO - 'tests': ['tp5o']}, 05:25:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:22 INFO - 'talos_options': ['--disable-e10s', 05:25:22 INFO - '--xperf_path', 05:25:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:25:22 INFO - 'tests': ['tp5n']}, 05:25:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:22 INFO - 'talos_options': ['--xperf_path', 05:25:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:25:22 INFO - 'tests': ['tp5n']}}, 05:25:22 INFO - 'talos.zip': {'path': '', 05:25:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:25:22 INFO - Running post-action listener: _resource_record_post_action 05:25:22 INFO - [mozharness: 2016-09-23 12:25:22.944377Z] Finished populate-webroot step (success) 05:25:22 INFO - [mozharness: 2016-09-23 12:25:22.944474Z] Running create-virtualenv step. 05:25:22 INFO - Running pre-action listener: _resource_record_pre_action 05:25:22 INFO - Running main action method: create_virtualenv 05:25:22 INFO - Creating virtualenv /builds/slave/test/build/venv 05:25:22 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 05:25:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:25:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:25:23 INFO - Using real prefix '/tools/python27' 05:25:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:25:23 INFO - Installing distribute.............................................................................................................................................................................................done. 05:25:26 INFO - Installing pip.................done. 05:25:26 INFO - Return code: 0 05:25:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:25:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:26 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 0x103f19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea5a58adf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d2dd30>, '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 0x103b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d25b20>, '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.jY0SPY2xlp/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.ZQ71r3EIeA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:25:26 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 05:25:26 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 05:25:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jY0SPY2xlp/Render', 05:25:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:26 INFO - 'HOME': '/Users/cltbld', 05:25:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:26 INFO - 'LOGNAME': 'cltbld', 05:25:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:26 INFO - 'MOZ_NO_REMOTE': '1', 05:25:26 INFO - 'NO_EM_RESTART': '1', 05:25:26 INFO - 'PAGER': '/bin/cat', 05:25:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:26 INFO - 'PWD': '/builds/slave/test', 05:25:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:26 INFO - 'SHELL': '/bin/bash', 05:25:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZQ71r3EIeA/Listeners', 05:25:26 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:25:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:26 INFO - 'USER': 'cltbld', 05:25:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:26 INFO - 'XPC_FLAGS': '0x0', 05:25:26 INFO - 'XPC_SERVICE_NAME': '0', 05:25:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:27 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 05:25:27 INFO - Cleaning up... 05:25:27 INFO - Return code: 0 05:25:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:25: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')]} 05:25:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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')]} 05:25:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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', '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 0x103f19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea5a58adf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d2dd30>, '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 0x103b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d25b20>, '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.jY0SPY2xlp/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.ZQ71r3EIeA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:25: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:25: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 psutil>=3.1.1 05:25:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jY0SPY2xlp/Render', 05:25:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:27 INFO - 'HOME': '/Users/cltbld', 05:25:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:27 INFO - 'LOGNAME': 'cltbld', 05:25:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:27 INFO - 'MOZ_NO_REMOTE': '1', 05:25:27 INFO - 'NO_EM_RESTART': '1', 05:25:27 INFO - 'PAGER': '/bin/cat', 05:25:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:27 INFO - 'PWD': '/builds/slave/test', 05:25:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:27 INFO - 'SHELL': '/bin/bash', 05:25:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZQ71r3EIeA/Listeners', 05:25:27 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:25:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:27 INFO - 'USER': 'cltbld', 05:25:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:27 INFO - 'XPC_FLAGS': '0x0', 05:25:27 INFO - 'XPC_SERVICE_NAME': '0', 05:25:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:27 INFO - Downloading/unpacking psutil>=3.1.1 05:25:27 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 05:25:27 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 05:25:27 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 05:25:27 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 05:25:27 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 05:25:27 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 05:25:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:25:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:25:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:25:30 INFO - Installing collected packages: psutil 05:25:30 INFO - Running setup.py install for psutil 05:25:30 INFO - building 'psutil._psutil_osx' extension 05:25:30 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 05:25:30 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 05:25:30 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 05:25:31 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 05:25:31 INFO - building 'psutil._psutil_posix' extension 05:25:31 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 05:25:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:25:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:25:31 INFO - ^ 05:25:31 INFO - 1 warning generated. 05:25:31 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 05:25:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:25:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:25:31 INFO - Successfully installed psutil 05:25:31 INFO - Cleaning up... 05:25:31 INFO - Return code: 0 05:25:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:31 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 0x103f19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea5a58adf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d2dd30>, '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 0x103b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d25b20>, '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.jY0SPY2xlp/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.ZQ71r3EIeA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:25:31 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 05:25:31 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 05:25:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jY0SPY2xlp/Render', 05:25:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:31 INFO - 'HOME': '/Users/cltbld', 05:25:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:31 INFO - 'LOGNAME': 'cltbld', 05:25:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:31 INFO - 'MOZ_NO_REMOTE': '1', 05:25:31 INFO - 'NO_EM_RESTART': '1', 05:25:31 INFO - 'PAGER': '/bin/cat', 05:25:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:31 INFO - 'PWD': '/builds/slave/test', 05:25:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:31 INFO - 'SHELL': '/bin/bash', 05:25:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZQ71r3EIeA/Listeners', 05:25:31 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:25:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:31 INFO - 'USER': 'cltbld', 05:25:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:31 INFO - 'XPC_FLAGS': '0x0', 05:25:31 INFO - 'XPC_SERVICE_NAME': '0', 05:25:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:25: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 05:25: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 05:25: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 05:25: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 05:25: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 05:25: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 05:25:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:25:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:25:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:25:34 INFO - Installing collected packages: mozsystemmonitor 05:25:34 INFO - Running setup.py install for mozsystemmonitor 05:25:34 INFO - Successfully installed mozsystemmonitor 05:25:34 INFO - Cleaning up... 05:25:34 INFO - Return code: 0 05:25:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:25:34 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')]} 05:25:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:34 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')]} 05:25:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:34 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 0x103f19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea5a58adf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d2dd30>, '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 0x103b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d25b20>, '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.jY0SPY2xlp/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.ZQ71r3EIeA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:25:34 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 05:25:34 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 05:25:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jY0SPY2xlp/Render', 05:25:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:34 INFO - 'HOME': '/Users/cltbld', 05:25:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:34 INFO - 'LOGNAME': 'cltbld', 05:25:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:34 INFO - 'MOZ_NO_REMOTE': '1', 05:25:34 INFO - 'NO_EM_RESTART': '1', 05:25:34 INFO - 'PAGER': '/bin/cat', 05:25:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:34 INFO - 'PWD': '/builds/slave/test', 05:25:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:34 INFO - 'SHELL': '/bin/bash', 05:25:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZQ71r3EIeA/Listeners', 05:25:34 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:25:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:34 INFO - 'USER': 'cltbld', 05:25:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:34 INFO - 'XPC_FLAGS': '0x0', 05:25:34 INFO - 'XPC_SERVICE_NAME': '0', 05:25:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:34 INFO - Downloading/unpacking jsonschema==2.5.1 05:25:34 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 05:25:34 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 05:25:34 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 05:25:34 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 05:25:34 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 05:25:34 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 05:25:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:25:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:25:37 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 05:25:37 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 05:25:37 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 05:25:37 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 05:25:37 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 05:25:37 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 05:25:37 INFO - Downloading functools32-3.2.3-2.tar.gz 05:25:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:25:37 INFO - warning: no files found matching '*.txt' 05:25:37 INFO - no previously-included directories found matching 'build' 05:25:37 INFO - no previously-included directories found matching 'dist' 05:25:37 INFO - no previously-included directories found matching '.git*' 05:25:37 INFO - Installing collected packages: jsonschema, functools32 05:25:37 INFO - Running setup.py install for functools32 05:25:37 INFO - warning: no files found matching '*.txt' 05:25:37 INFO - no previously-included directories found matching 'build' 05:25:37 INFO - no previously-included directories found matching 'dist' 05:25:37 INFO - no previously-included directories found matching '.git*' 05:25:37 INFO - Successfully installed jsonschema functools32 05:25:37 INFO - Cleaning up... 05:25:38 INFO - Return code: 0 05:25:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:25: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')]} 05:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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')]} 05:25:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103f19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea5a58adf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d2dd30>, '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 0x103b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d25b20>, '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.jY0SPY2xlp/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.ZQ71r3EIeA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:25:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:25:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:25:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jY0SPY2xlp/Render', 05:25:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:38 INFO - 'HOME': '/Users/cltbld', 05:25:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:38 INFO - 'LOGNAME': 'cltbld', 05:25:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:38 INFO - 'MOZ_NO_REMOTE': '1', 05:25:38 INFO - 'NO_EM_RESTART': '1', 05:25:38 INFO - 'PAGER': '/bin/cat', 05:25:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:38 INFO - 'PWD': '/builds/slave/test', 05:25:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:38 INFO - 'SHELL': '/bin/bash', 05:25:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZQ71r3EIeA/Listeners', 05:25:38 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:25:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:38 INFO - 'USER': 'cltbld', 05:25:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:38 INFO - 'XPC_FLAGS': '0x0', 05:25:38 INFO - 'XPC_SERVICE_NAME': '0', 05:25:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:25:38 INFO - Cleaning up... 05:25:38 INFO - Return code: 0 05:25:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:25: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')]} 05:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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')]} 05:25:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103f19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea5a58adf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d2dd30>, '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 0x103b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d25b20>, '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.jY0SPY2xlp/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.ZQ71r3EIeA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:25:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:25:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:25:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jY0SPY2xlp/Render', 05:25:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:38 INFO - 'HOME': '/Users/cltbld', 05:25:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:38 INFO - 'LOGNAME': 'cltbld', 05:25:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:38 INFO - 'MOZ_NO_REMOTE': '1', 05:25:38 INFO - 'NO_EM_RESTART': '1', 05:25:38 INFO - 'PAGER': '/bin/cat', 05:25:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:38 INFO - 'PWD': '/builds/slave/test', 05:25:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:38 INFO - 'SHELL': '/bin/bash', 05:25:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZQ71r3EIeA/Listeners', 05:25:38 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:25:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:38 INFO - 'USER': 'cltbld', 05:25:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:38 INFO - 'XPC_FLAGS': '0x0', 05:25:38 INFO - 'XPC_SERVICE_NAME': '0', 05:25:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:38 INFO - Downloading/unpacking blobuploader==1.2.4 05:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:40 INFO - Downloading blobuploader-1.2.4.tar.gz 05:25:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:25:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:25:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:25:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:25:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:42 INFO - Downloading docopt-0.6.1.tar.gz 05:25:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:25:42 INFO - Installing collected packages: blobuploader, requests, docopt 05:25:42 INFO - Running setup.py install for blobuploader 05:25:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:25:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:25:42 INFO - Running setup.py install for requests 05:25:42 INFO - Running setup.py install for docopt 05:25:43 INFO - Successfully installed blobuploader requests docopt 05:25:43 INFO - Cleaning up... 05:25:43 INFO - Return code: 0 05:25:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:25:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:43 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 0x103f19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea5a58adf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d2dd30>, '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 0x103b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d25b20>, '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.jY0SPY2xlp/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.ZQ71r3EIeA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:25:43 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 05:25:43 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 05:25:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jY0SPY2xlp/Render', 05:25:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:43 INFO - 'HOME': '/Users/cltbld', 05:25:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:43 INFO - 'LOGNAME': 'cltbld', 05:25:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:43 INFO - 'MOZ_NO_REMOTE': '1', 05:25:43 INFO - 'NO_EM_RESTART': '1', 05:25:43 INFO - 'PAGER': '/bin/cat', 05:25:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:43 INFO - 'PWD': '/builds/slave/test', 05:25:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:43 INFO - 'SHELL': '/bin/bash', 05:25:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZQ71r3EIeA/Listeners', 05:25:43 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:25:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:43 INFO - 'USER': 'cltbld', 05:25:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:43 INFO - 'XPC_FLAGS': '0x0', 05:25:43 INFO - 'XPC_SERVICE_NAME': '0', 05:25:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:25:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-qXveuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:25:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-DIwWkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:25:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-OCUuZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:25:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-UkoBMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:25:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-nWJgnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:25:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-3xYC7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:25:44 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Yzhdvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:25:44 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-DqU8xk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:25:44 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-kgiU62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:25:44 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-gQvugd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:25:44 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-xr2m34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:25:44 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-HnQwfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:25:44 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-xgOTPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:25:44 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_3iZ2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:25:44 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Bjp11A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:25:44 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-R9bWTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:25:45 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-CabRkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:25:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:25:45 INFO - Running setup.py install for manifestparser 05:25:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:25:45 INFO - Running setup.py install for mozcrash 05:25:45 INFO - Running setup.py install for mozdebug 05:25:45 INFO - Running setup.py install for mozdevice 05:25:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:25:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:25:45 INFO - Running setup.py install for mozfile 05:25:45 INFO - Running setup.py install for mozhttpd 05:25:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:25:46 INFO - Running setup.py install for mozinfo 05:25:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:25:46 INFO - Running setup.py install for mozInstall 05:25:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:25:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:25:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:25:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:25:46 INFO - Running setup.py install for mozleak 05:25:46 INFO - Running setup.py install for mozlog 05:25:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:25:46 INFO - Running setup.py install for moznetwork 05:25:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:25:46 INFO - Running setup.py install for mozprocess 05:25:46 INFO - Running setup.py install for mozprofile 05:25:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:25:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:25:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:25:47 INFO - Running setup.py install for mozrunner 05:25:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:25:47 INFO - Running setup.py install for mozscreenshot 05:25:47 INFO - Running setup.py install for moztest 05:25:47 INFO - Running setup.py install for mozversion 05:25:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:25:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:25:47 INFO - Cleaning up... 05:25:47 INFO - Return code: 0 05:25:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:25:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:47 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 0x103f19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea5a58adf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d2dd30>, '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 0x103b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d25b20>, '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.jY0SPY2xlp/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.ZQ71r3EIeA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:25:47 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 05:25:47 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 05:25:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jY0SPY2xlp/Render', 05:25:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:47 INFO - 'HOME': '/Users/cltbld', 05:25:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:47 INFO - 'LOGNAME': 'cltbld', 05:25:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:47 INFO - 'MOZ_NO_REMOTE': '1', 05:25:47 INFO - 'NO_EM_RESTART': '1', 05:25:47 INFO - 'PAGER': '/bin/cat', 05:25:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:47 INFO - 'PWD': '/builds/slave/test', 05:25:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:47 INFO - 'SHELL': '/bin/bash', 05:25:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZQ71r3EIeA/Listeners', 05:25:47 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:25:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:47 INFO - 'USER': 'cltbld', 05:25:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:47 INFO - 'XPC_FLAGS': '0x0', 05:25:47 INFO - 'XPC_SERVICE_NAME': '0', 05:25:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:25:48 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-GSoW7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:25:48 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)) 05:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:25:48 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-EGfu_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:25:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:25:48 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-IpQYPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:25:48 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)) 05:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:25:48 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Sf7Iw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:25:48 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)) 05:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:25:48 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-MLvkO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:25:48 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)) 05:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:25:48 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-x4J5AO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:25:48 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)) 05:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:25:48 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-R3Xp12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:25:48 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)) 05:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:25:48 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-wFs7Il-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:25:48 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)) 05:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:25:48 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-C5jaKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:25:48 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)) 05:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:25:48 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-snvSNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:25:49 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)) 05:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:25:49 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-yfagll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:25:49 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)) 05:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:25:49 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_x6Z0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:25:49 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)) 05:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:25:49 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-acBlkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:25:49 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)) 05:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:25:49 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-sa7ZWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:25:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:25:49 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Raxh2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:25:49 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)) 05:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:25:49 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-zUSWUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:25:49 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)) 05:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:25:49 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-K1JxgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:25:49 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)) 05:25:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:25:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:25:49 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)) 05:25:49 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)) 05:25:49 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)) 05:25:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:25:49 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 05:25:49 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 05:25:49 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 05:25:49 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 05:25:49 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 05:25:49 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 05:25:52 INFO - Downloading blessings-1.6.tar.gz 05:25:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:25:52 INFO - Installing collected packages: blessings 05:25:52 INFO - Running setup.py install for blessings 05:25:52 INFO - Successfully installed blessings 05:25:52 INFO - Cleaning up... 05:25:52 INFO - Return code: 0 05:25:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:25:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:25:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:25:52 INFO - Reading from file tmpfile_stdout 05:25:52 INFO - Current package versions: 05:25:52 INFO - blessings == 1.6 05:25:52 INFO - blobuploader == 1.2.4 05:25:52 INFO - docopt == 0.6.1 05:25:52 INFO - functools32 == 3.2.3-2 05:25:52 INFO - jsonschema == 2.5.1 05:25:52 INFO - manifestparser == 1.1 05:25:52 INFO - mozInstall == 1.12 05:25:52 INFO - mozcrash == 0.17 05:25:52 INFO - mozdebug == 0.1 05:25:52 INFO - mozdevice == 0.48 05:25:52 INFO - mozfile == 1.2 05:25:52 INFO - mozhttpd == 0.7 05:25:52 INFO - mozinfo == 0.9 05:25:52 INFO - mozleak == 0.1 05:25:52 INFO - mozlog == 3.4 05:25:52 INFO - moznetwork == 0.27 05:25:52 INFO - mozprocess == 0.23 05:25:52 INFO - mozprofile == 0.28 05:25:52 INFO - mozrunner == 6.12 05:25:52 INFO - mozscreenshot == 0.1 05:25:52 INFO - mozsystemmonitor == 0.3 05:25:52 INFO - moztest == 0.7 05:25:52 INFO - mozversion == 1.4 05:25:52 INFO - psutil == 3.1.1 05:25:52 INFO - requests == 1.2.3 05:25:52 INFO - wsgiref == 0.1.2 05:25:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:25:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:53 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 0x103f19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea5a58adf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d2dd30>, '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 0x103b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d25b20>, '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.jY0SPY2xlp/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.ZQ71r3EIeA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:25:53 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 05:25:53 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 05:25:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jY0SPY2xlp/Render', 05:25:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:53 INFO - 'HOME': '/Users/cltbld', 05:25:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:53 INFO - 'LOGNAME': 'cltbld', 05:25:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:53 INFO - 'MOZ_NO_REMOTE': '1', 05:25:53 INFO - 'NO_EM_RESTART': '1', 05:25:53 INFO - 'PAGER': '/bin/cat', 05:25:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:53 INFO - 'PWD': '/builds/slave/test', 05:25:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:53 INFO - 'SHELL': '/bin/bash', 05:25:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZQ71r3EIeA/Listeners', 05:25:53 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:25:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:53 INFO - 'USER': 'cltbld', 05:25:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:53 INFO - 'XPC_FLAGS': '0x0', 05:25:53 INFO - 'XPC_SERVICE_NAME': '0', 05:25:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:53 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)) 05:25:53 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)) 05:25:53 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)) 05:25:53 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)) 05:25:53 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)) 05:25:53 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)) 05:25:53 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)) 05:25:53 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)) 05:25:53 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)) 05:25:53 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)) 05:25:53 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)) 05:25:53 INFO - Cleaning up... 05:25:53 INFO - Return code: 0 05:25:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:53 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 0x103f19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea5a58adf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d2dd30>, '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 0x103b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d25b20>, '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.jY0SPY2xlp/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.ZQ71r3EIeA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:25:53 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 05:25:53 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 05:25:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jY0SPY2xlp/Render', 05:25:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:53 INFO - 'HOME': '/Users/cltbld', 05:25:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:53 INFO - 'LOGNAME': 'cltbld', 05:25:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:53 INFO - 'MOZ_NO_REMOTE': '1', 05:25:53 INFO - 'NO_EM_RESTART': '1', 05:25:53 INFO - 'PAGER': '/bin/cat', 05:25:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:53 INFO - 'PWD': '/builds/slave/test', 05:25:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:53 INFO - 'SHELL': '/bin/bash', 05:25:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZQ71r3EIeA/Listeners', 05:25:53 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:25:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:53 INFO - 'USER': 'cltbld', 05:25:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:53 INFO - 'XPC_FLAGS': '0x0', 05:25:53 INFO - 'XPC_SERVICE_NAME': '0', 05:25:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:25:53 INFO - Cleaning up... 05:25:53 INFO - Return code: 0 05:25:53 INFO - Running post-action listener: _resource_record_post_action 05:25:53 INFO - Running post-action listener: _start_resource_monitoring 05:25:53 INFO - Starting resource monitoring. 05:25:53 INFO - [mozharness: 2016-09-23 12:25:53.830153Z] Finished create-virtualenv step (success) 05:25:53 INFO - [mozharness: 2016-09-23 12:25:53.831108Z] Running install step. 05:25:53 INFO - Running pre-action listener: _resource_record_pre_action 05:25:53 INFO - Running main action method: install 05:25:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:25:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:25:54 INFO - Reading from file tmpfile_stdout 05:25:54 INFO - Detecting whether we're running mozinstall >=1.0... 05:25:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:25:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:25:54 INFO - Reading from file tmpfile_stdout 05:25:54 INFO - Output received: 05:25:54 INFO - Usage: mozinstall [options] installer 05:25:54 INFO - Options: 05:25:54 INFO - -h, --help show this help message and exit 05:25:54 INFO - -d DEST, --destination=DEST 05:25:54 INFO - Directory to install application into. [default: 05:25:54 INFO - "/builds/slave/test"] 05:25:54 INFO - --app=APP Application being installed. [default: firefox] 05:25:54 INFO - mkdir: /builds/slave/test/build/application 05:25:54 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'] 05:25:54 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 05:26:28 INFO - Reading from file tmpfile_stdout 05:26:28 INFO - Output received: 05:26:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:26:28 INFO - Running post-action listener: _resource_record_post_action 05:26:28 INFO - [mozharness: 2016-09-23 12:26:28.255160Z] Finished install step (success) 05:26:28 INFO - [mozharness: 2016-09-23 12:26:28.255262Z] Running run-tests step. 05:26:28 INFO - Running pre-action listener: _resource_record_pre_action 05:26:28 INFO - Running main action method: run_tests 05:26:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:26:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:26:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:26:28 INFO - 2016-09-23 05:26:28.267 screenresolution[1643:7430] starting screenresolution argv=screenresolution get 05:26:28 INFO - 2016-09-23 05:26:28.270 screenresolution[1643:7430] Display 0: 1600x1200x32@60 05:26:28 INFO - 2016-09-23 05:26:28.275 screenresolution[1644:7432] starting screenresolution argv=screenresolution list 05:26:28 INFO - Available Modes on Display 0 05:26:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:26:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:26:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:26:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:26:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:26:28 INFO - Intel Iris: 05:26:28 INFO - Chipset Model: Intel Iris 05:26:28 INFO - Type: GPU 05:26:28 INFO - Bus: Built-In 05:26:28 INFO - VRAM (Dynamic, Max): 1536 MB 05:26:28 INFO - Vendor: Intel (0x8086) 05:26:28 INFO - Device ID: 0x0a2e 05:26:28 INFO - Revision ID: 0x0009 05:26:28 INFO - Displays: 05:26:28 INFO - Display: 05:26:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:26:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:26:28 INFO - Display Serial Number: 1600x1200 60 05:26:28 INFO - Main Display: Yes 05:26:28 INFO - Mirror: Off 05:26:28 INFO - Online: Yes 05:26:28 INFO - Rotation: Supported 05:26:28 INFO - Return code: 0 05:26:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:26:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:26:28 INFO - Python 2.7.3 05:26:28 INFO - Return code: 0 05:26:28 INFO - grabbing minidump binary from tooltool 05:26:28 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')]} 05:26:28 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 0x103d2dd30>, '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 0x103b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d25b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:26:28 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 05:26:28 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 05:26:28 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 05:26:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:26:28 INFO - Return code: 0 05:26:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:26:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:26:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:26:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:26:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:26:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:26:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0111 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:26:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jY0SPY2xlp/Render', 05:26:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:28 INFO - 'HOME': '/Users/cltbld', 05:26:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:28 INFO - 'LOGNAME': 'cltbld', 05:26:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:26:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:26:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:28 INFO - 'MOZ_NO_REMOTE': '1', 05:26:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:26:28 INFO - 'NO_EM_RESTART': '1', 05:26:28 INFO - 'PAGER': '/bin/cat', 05:26:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:28 INFO - 'PWD': '/builds/slave/test', 05:26:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:26:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:28 INFO - 'SHELL': '/bin/bash', 05:26:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZQ71r3EIeA/Listeners', 05:26:28 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:26:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:28 INFO - 'USER': 'cltbld', 05:26:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:28 INFO - 'XPC_FLAGS': '0x0', 05:26:28 INFO - 'XPC_SERVICE_NAME': '0', 05:26:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:26:28 INFO - mozversion application_buildid: 20160923030856 05:26:28 INFO - mozversion application_changeset: 9eae36f83dbae7434e74cd9c689a965031778370 05:26:28 INFO - mozversion application_display_name: Nightly 05:26:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:26:28 INFO - mozversion application_name: Firefox 05:26:28 INFO - mozversion application_remotingname: firefox 05:26:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:26:28 INFO - mozversion application_vendor: Mozilla 05:26:28 INFO - mozversion application_version: 52.0a1 05:26:28 INFO - mozversion platform_buildid: 20160923030856 05:26:28 INFO - mozversion platform_changeset: 9eae36f83dbae7434e74cd9c689a965031778370 05:26:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:26:28 INFO - mozversion platform_version: 52.0a1 05:26:28 INFO - using testdate: 1474633588 05:26:28 INFO - actual date: 1474633588 05:26:28 INFO - starting webserver on 'localhost:49269' 05:26:28 INFO - SUITE-START | Running 2 tests 05:26:28 INFO - TEST-START | dromaeo_css 05:26:28 INFO - operating with platform_type : mac_ 05:26:28 INFO - Initialising browser for dromaeo_css test... 05:26:28 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpDIVuOC/profile http://localhost:49269/getInfo.html) 05:26:31 INFO - PROCESS | 1654 | 2016-09-23 05:26:31.604 plugin-container[1656:7571] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 05:26:31 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:26:31 INFO - PROCESS | 1654 | 2016-09-23 05:26:31.606 plugin-container[1656:7571] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.CFPasteboardClient' 05:26:31 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:26:31 INFO - PROCESS | 1654 | 2016-09-23 05:26:31.606 plugin-container[1656:7571] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:26:31 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 05:26:31 INFO - PROCESS | 1654 | colorDepth:24 05:26:31 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 05:26:31 INFO - PROCESS | 1654 | __metrics 05:26:31 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 05:26:32 INFO - TEST-INFO | 1654: exit 0 05:26:32 INFO - Browser initialized. 05:26:32 INFO - Running cycle 1/1 for dromaeo_css test... 05:26:32 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpDIVuOC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:26:34 INFO - PROCESS | 1657 | 2016-09-23 05:26:34.920 plugin-container[1658:7690] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x881f, name = 'com.apple.tsm.portname' 05:26:34 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:26:34 INFO - PROCESS | 1657 | 2016-09-23 05:26:34.922 plugin-container[1658:7690] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 05:26:34 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:26:34 INFO - PROCESS | 1657 | 2016-09-23 05:26:34.922 plugin-container[1658:7690] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:26:44 INFO - PROCESS | 1657 | RSS: Main: 239591424 05:26:44 INFO - PROCESS | 1657 | 05:27:34 INFO - PROCESS | 1657 | 1474633654080 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] 05:29:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49269/tests/dromaeo/cssquery-ext.html) 05:29:46 INFO - PROCESS | 1657 | RSS: Main: 255336448 05:29:46 INFO - PROCESS | 1657 | 05:32:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-ext.html (next: http://localhost:49269/tests/dromaeo/cssquery-jquery.html) 05:32:49 INFO - PROCESS | 1657 | RSS: Main: 265256960 05:32:49 INFO - PROCESS | 1657 | 05:35:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49269/tests/dromaeo/cssquery-mootools.html) 05:35:52 INFO - PROCESS | 1657 | RSS: Main: 271151104 05:35:52 INFO - PROCESS | 1657 | 05:38:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49269/tests/dromaeo/cssquery-prototype.html) 05:38:54 INFO - PROCESS | 1657 | RSS: Main: 270721024 05:38:54 INFO - PROCESS | 1657 | 05:41:57 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49269/tests/dromaeo/cssquery-yui.html) 05:41:58 INFO - PROCESS | 1657 | RSS: Main: 276004864 05:41:58 INFO - PROCESS | 1657 | 05:45:00 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-yui.html (next: http://localhost:49269/tests/dromaeo/cssquery-dojo.html) 05:45:01 INFO - PROCESS | 1657 | RSS: Main: 278704128 05:45:01 INFO - PROCESS | 1657 | 05:45:01 INFO - PROCESS | 1657 | __start_tp_report 05:45:01 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 05:45:01 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 05:45:01 INFO - PROCESS | 1657 | |i|pagename|runs| 05:45:01 INFO - PROCESS | 1657 | |0;dojo.html;2722.224166112658;3342.882999095032;3361.4789707595314;3368.040108569057;3392.1350055735797;5521.033819081655;5628.98678237917;5629.183768353586;5688.829335119953;5692.117721753125;5397.595180361469;5559.054960656688;5611.80358687446;5643.238162848014;5653;5104.795808167669;5115.641905066647;5223.529882310591;5242.187460943541;5246.422893481733;5158.406783219922;5228.633995620308;5307.124324486455;5314.309139811839;5336.573074154058;4290.184864875695;5220.269162317279;5235.842924712246;5257.921131183035;5261.579073674096;4178.373244013392;4964.851054468371;5017.2724954881705;5046.924296135561;5116.897662046776;4346.826126954918;4933.383327084114;5057.671251368649;5118.10433174193;5127;4375.299952007664;5662.2639056922335;5670.773169073233;5702.800401985952;5726.427357264281;6058.1215723719815;6135.385782596134;6142.355052719445;6179.443850053517;6217.751289948396;6167.105769663419;6168.84577885552;6182.1345011698395;6188.1027251048345;6192.380761923771;5679.772809087673;5872.530197584183;5900.822975310747;5915.349311575723;5942.435468630473;6142.877142457126;6159.4456498915315;6197.81406557804;6238.532110091761;6289.811305660837;5059.924101138486;6109.11417844419;6134.539909506805;6145.201123853871;6148.907766383508;6194.442500174916;6206.875862482725;6207.720652570645;6211.0372892201785;6217.9689101555105;4924.507549245047;5972.641641501437;6096.634201947897;6131.743695907034;6176.227971503562;6119.204503414528;6120.387961203844;6179.752809887555;6202.224721909762;6208.4722798562625;4796.37647105874;4881.877953051202;4899.58353539946;4901.264810278487;4944.233643785219;4350.434443522322;4355.303151495746;4381.38660587518;4389.604935555815;4411.316245981878;5518;5853;5862.912056319158;5908.261467316585;5949.464548190597;6158.938410615926;6175.320714721467;6181.381861813783;6195.101710251988;6195.876082478415;5669.06460434032;5688.317401911797;5803.071508558611;5831.096180092092;5891.734871930774;5365.678059316453;5367.624266301322;5395.163749618815;5401.810936617199;5439.184122381675;6175.783847565313;6219.502439804804;6239.625622462667;6281.96859015702;6293.527985401021;5366.3560372753955;6127.908081378872;6135.477655820289;6207;6216.2540495140875;5933.347331793413;6129.9701281150365;6164.506839452923;6214.8135555931585;6221.346758590283;6047.365026672312;6055.848603784941;6064.48451881595;6072.72672729737;6079.878402431838;5311.575073993994;6087.695615219132;6104.004449388181;6119.502553941335;6152.138700581832;6107.480864126422;6117.049106809072;6125.876495711566;6133.26400831903;6153.876922461615;5368.221607866915;5998.25021872266;6102.9694851525455;6115.3578874217565;6127.295361033538;4333.956660433355;4444.422225110844;4784.808607655794;5628.971855140698;5883.558733094949;4936.481669424802;5808.767649293981;6083.270007599116;6111.1444397785235;6125.632272693224;4952.950470495393;4956.801727930987;5961.791337303173;5972.313183983897;5978.983572792548 05:45:01 INFO - PROCESS | 1657 | |1;ext.html;435.89935413081963;485.9600455026242;525.2934802690385;526.8577484079299;536.0458384076345;40648;42018.36972445412;42136.15727685444;42254.15491690168;42293;43047.78476107615;43335.34996975048;43463.565364346345;43573.34639980403;43627.56372436275;9739.269554783385;9940.502974851264;9944.353617014907;9959.651412200574;9969.10278074973;9853.06395892389;9954.850677239847;9958.352707074017;9960.950195249014;9990.250731195154;9909.801803963916;9943.303968722192;9959.452230127341;9961.103500684936;9961.953994830546;8476.957615211946;9900.50497475127;9938.90061099387;9950.502474876263;9963.651272205438;9745.171660408874;9799.265055120895;9809.411435313832;9821.214302855755;9830.508474576314;40614.187716245804;40821.79589102065;40904.590954090374;41060;41067.38398923989;3162.908796465216;3420.640832712579;3704.9259014819554;3903.945934597643;4035.878923632296;30317.93887213937;30849.537256941163;31081.689183108105;31103.37793244145;31818.522722159185;3261.7390608751243;3285.1130194847497;3285.572875526166;3355.3457075870215;3389.49157626358;451206;460892;463374;464633;466324.66837665933;32841.67158328386;59279;59489.70255148697;59521;59694.40305596975;33499;34518.82740586281;34777.30445391129;35034.12414689602;35164.82417587947;14363.784543231859;14479.710405791826;14510;14511.274436278354;14517.637059073608;12139.575114870937;12232.204906681038;12232.449539770732;12264.509419623293;12294.75410491803;7749.263819937097;7763.650635721406;7764.288447490254;7800.141984381714;7817.335526480313;3231.5475833383343;3241.7730758846656;3253.170441537393;3253.2842774589553;3274.279658475132;916.6883259691737;971.577363846729;1000.849872519128;1023.82083135451;1026.1431704526653;24459.38851528726;29983.800647973836;30076.398472030436;30147.944821931127;30339.69660303413;906.909309069101;1045.9163266938626;1057.5346847387125;1096.4408151842617;1102.4873433853136;13492.59522214335;13942.58172254834;14166.433342666383;14374.928125359307;15662;672314;681678;683697;683763;684835;57528;57981.4201857976;58065;58118.7094064527;58230.7088464555;1292.7802273613509;1311.888489478405;1349.0826238157952;1354.1130559483356;1357.5835013675674;26940.730592694606;29712.851435742683;29905.850470747508;30355.392892142474;30587;50235;50755.49244507508;50758;50814.74592627013;50917;547089.2645536747;560597;563557;564846;565037;26219.21342359732;26406.73593264043;27052.05317813867;27239.31901702394;27401;47369.28946065881;47822.76088619535;47970.80072998203;48079.27881081856;48827.51172488372;38909.02727431837;38944.02639934024;39069;40248.3962740565;40883;31673.891413800407;37775.62224377721;38853.640122596706;39958;40212.59787402205;37320.44019339658;37714.622853772205;38206.235875282895;38610.613893861824;38807.80596097114;27281;28640.42719145647;30539.694603053686;34361.82819085989;34794.304113918086;25493.958564447064;25605.615915760907;25784.484310314063;26146;26405.471890561697 05:45:01 INFO - PROCESS | 1657 | |2;jquery.html;370.53312825839436;376.61208954776583;385.3814627522825;391.21561269654336;404.7086098009434;18718.53203669907;20843.27048553299;20879.791202087974;21005.264815731472;21202.787972120273;20252.59494810103;20482.897585512088;20961.685574716354;21059.368218953456;21253;5884.970575147118;5922.526197904179;6066.120412540179;6155.415235552616;6169.321374648807;4154.127633197032;4381.561843815625;5895.528357731371;6025.969870150665;6130.141780150782;6682.264950855402;6808.761693340734;6819.624920629363;6825.31746825316;6835.58986460814;6529.5429319947625;6756.6621668916605;6799.4900382471515;6825.1127353443735;6830.351116643936;5087.516685914832;5379.112446446371;5856.180134781134;5905.379935106795;5905.557083218776;13051.869481305255;13111.672208194872;13362.599122026355;13366.264855433044;16867.578310542212;1633.2895190592012;1864.6457173137196;1866.8332292317302;2000.6795514960202;2062.577171679802;10004.94997525015;10035.899641003569;10050.145737612313;10259.589616415335;10414.16686665065;1827.7349784281337;1837;1912.1204246046834;1920.5486710622993;1921.28912302449;91752;110729.44635276771;120078.79921200764;120390;120690;11037.061849742862;11135.33188008722;11481.770364592661;11528.423578821026;11667.299962002136;14158.858411415755;14223.431062757581;14272.571822845332;14286.357113929898;14337.928310358382;8025.919740802634;8276.048254450816;8318.292945099613;8395.118512556215;8399.11809260031;8153;8159.592020398956;8190.303824174893;8294.83410331802;8298.543580103153;3878.8448462061765;4296;4383.890402739957;4432.246518091933;4449.377087207794;1591.1964457948661;1685.6881476926808;1689.4340395967488;1905.9047047647562;1971.9704204436946;740.6889106575252;777.6772639354605;920.802027564077;960.5629438605417;1004.754104909921;15480.458183963732;19900;20531.897340513202;20833.791662083186;21077;888.1784349476837;918.3479729392072;989.3618615992644;1004.6734811186394;1008;10751;10874.456277186267;10975.396353200491;11021.173411993972;12022;529500;533822;538226;539980;541037;121889;128650;129773.35113324373;136845;145527;790.9288164065147;996.2627655534852;1051.3796859852723;1052.4211683574003;1058.655936820521;17696.823031769516;18016.729749053513;18981.71527427062;19020;19175.945323007367;55194;55478.89042219214;55968.72015639896;56374.436255636916;56652.433475664715;79910;80812.59593702228;81525.59237203776;81850;82890.58554707188;6577.375149360803;6961.477889158232;7255.637218139177;8243.340532757486;8250.174982501701;10579.4710264488;12684.23894566329;12877.034222433167;13381.130226535244;13555.79666305026;10348.379097254188;10933.83599246028;11213.271137376045;11359.148063895076;11441.713957151138;11094.889051109385;11204.719882003015;11242.044426223863;11284.774304514029;11402.315861048364;10957.945210273898;11270.549178032787;11544.538218471167;11616.535338586362;11669.649910502698;6690.632015239208;8144.22629850187;8213.835723285381;8296.70961516344;8308.70919517814;9202.89565252174;11240.718982025515;11249.606263780728;11280.153988451064;11357.886421136014 05:45:01 INFO - PROCESS | 1657 | |3;mootools.html;1393.5401317565204;1510.3505492638162;1670.6157583755744;1718.1924495487117;1862.5436767991853;7889.921100788991;8109.351251899849;8240.958795206023;8877.201051905318;8885.378023538355;8318.459300145487;8586.31309495239;8603.91396086039;8726.38915275931;8780.780480487974;2352.4354155002807;2373.8575685458927;2382.309130352196;2386.343755467252;2390.247072172269;1987.840972722186;2341.1571834139695;2364.0898254172093;2384.856908585482;2386.319898828834;2350.4711439925964;2357.033616217351;2367.7750613691674;2379.773921477457;2384.7496012918655;2336.7312759032757;2340.3564019894493;2358;2359.1860808021206;2363.4327761337327;2351.459164392182;2357.2103345379146;2362.65741467488;2363.1610778173845;2367.6448532720055;7578.522188173253;8612.181842724995;8664.913350866475;8856.51289179095;8878.556072196365;4955.5044495550155;5045.243213517965;5254.133068043769;5276.7889284428575;5299.390570084451;3429.3827111120236;3621.2214373909737;3621.2938476996997;3630.8910732678064;3654.6528079832374;1034.8085604163255;1061.7611037516574;1070.0904231403322;1090.5801266512372;1093.4423444043516;9895.158911492532;11141.275817071864;11465.65603031902;11595.130365222558;11624.825627615592;4467.843625473093;4644.419447569054;4738.147133515931;4744.122337367598;4755.881102972384;9825.606975720892;9928.205743540499;9932.205423566098;9932.80134397308;9935.850962235572;5286.286351342596;5290;5291.682499049993;5305.575553955674;5312.282841816305;4317.050248945227;4463.419755431773;4635.258358662665;4767.998720268783;4842.007388485352;3224.4196044712176;3317.834108294576;3801.0497375656087;3914.8629797956933;3944.467496887941;1282.499825068224;1291.4834066373526;1291.6706239908951;1298.9285589292679;1301.4859130643342;761.417515600566;885.4687187687465;895.6193617712577;912.7216199059218;913.3150137396952;5544.445555444504;5650.152477128464;5829.562782791308;5861.325947516005;5928.199693041471;422.4128461438603;440.88757366871886;441.41292081531486;442.4314755539117;929.9636686219499;3735.906602334909;3900.414937759359;3933.645971862546;3936.232434675268;4025.6980726445636;10457.215708821868;10670.199735019905;10779.544761457102;10789.136869050457;10794.514246858911;10730.409827459409;10734.516946737416;10744.033037026706;10764.085052770446;10770.24608277413;8086.878696819553;8164.183581641788;8169.346452283803;8193.95903020493;8198.672053117927;4468.865934021985;5443.428440013741;5582.995060889079;5677.51741102011;5752.108423194411;9457.29070319715;9797.755056123653;9806.85289720669;9827.705168844946;9848.655297064568;9800.411975281506;9889.802203955736;9914.454704991007;10079.949600251952;10156.644517441853;6820.93179068203;6832.487563432663;7023.824404389931;7328.3404493595835;7503.88744168827;6665.733370665119;8317.085120636604;8327.333813294801;8329.791755206168;8350.58247087631;7302.306280903305;7426.517276377018;7478.06524184477;7485.214052524407;7570.507916985378;7208.171060328129;7269.418446524372;7302.452316076422;7339.889901651372;7340.5228660139055;5871.794487192928;6960;7024.1571011478945;7028.191757947901;7033.085698859116;4365.978170109256;4689.953100468952;4825.9034819304115;4869;4917.606591472745;3711.406175011985;4111.465509483867;4787.114383839001;4828.830990915301;4892.730899800546 05:45:01 INFO - PROCESS | 1657 | |4;prototype.html;304.3486937952781;309.8466259201696;311.8846026970021;315.76633291364385;316.38621075114264;4628.514006029369;4682.57856792889;4859.833639926419;5194.454582268864;5256.737163141842;4406.413691071211;4986.4514903360605;5223.268742376061;5376.301080859483;5420.5934554908345;3937.8424863005516;4007.5792041835625;4033.35466325388;4089.795510224492;4132.173565286939;4105.794710264475;4120.237756015147;4148.336266197409;4157.708960372775;4180.47744031996;3392.7285817134693;3697.4268988306853;3931.2923673738715;4067.8677418387824;4170.562090980449;3241;3816.9045773855596;3990.6009399060154;4098.918021639565;4170.582941705836;3047.2686555226805;3354.7987120772586;3362.7557803612576;3528.9294214115575;3562.1985053363046;4707.5057119002695;4982.9252561211615;5025.497450254945;5030;5387.703676297802;3952.9011774705573;4020.994751312172;4127.9174416511505;4648.093621743761;4728.71627702339;4774.713517188945;5057.645964782467;5506.256655351556;5683.431656834325;5760.855978600526;3401.676840700154;3909.6285852843935;3915.941260881089;3919.060065267682;3928.194720082376;5003.5246651568405;5383.973080134574;6047.727852246616;6112.297085835142;6155.907661385038;3293.604767427924;3883;3930.6265904739003;3943.743656662308;3948.782816945038;3310.4372256716415;3922.882313530599;3991.960080399217;4023.959760402417;4029.395590661424;3710.6289371062676;4001.4998125234347;4705.85882423521;4706.223473126895;4725.503822098699;5364.383095943938;5604.943950560524;5648.943510564842;5851.327097383855;5881.882362352729;1190.4047976011993;1347.786991707471;1366.2827015816576;1371.739369519808;1380.9090818253467;699.7620808924888;797.6769408389612;823.1644880446352;837.5477242289096;846.530175752451;4177.665786737054;5215.608829337739;5520.316303527382;5553.694546799884;5559.663462499177;5980.252468441445;6057.666828324396;6060.212172417558;6091.02543593023;6114;4583.633309335245;5172.560332371715;5468;5533.335999680065;5533.6403133796175;2435.9756402436105;2764.557670772667;2941.896788704236;3170.4134735073626;3170.8414579270943;6229.96885015572;6261.7182226799905;6309.49524038085;6313.652749098752;6315.873682526324;5730.398308177581;6125.621735109565;6145.877082458326;6171.290301615282;6236.906446403307;5470.577649811078;6292.748290068529;6302.779402720883;6307.779227727099;6389.2013498313645;4884.633652476007;5621.8594535136945;6052.303985041601;6063.969680151571;6115.798312607547;5462.863428414322;5760.588655779361;5968.283805943314;5971.641701497924;6007.098935159761;5864.296284445893;6299.212598425197;6317.747290108344;6322.620642761265;6337.6197428154455;3352.9497057544645;3907.94832712828;3965.4448377226636;3981.701372397024;4000.199860097885;5267.2889159962915;5873.207117039145;6098.878022439438;6107.6868473278455;6123.724432400463;4523.883266227717;4655.674102812771;5579.130991118021;5929.377415371324;5940.16837642739;5801.535877129904;5861.563916840401;5886.82035754619;5907.881842363215;5968.955432799329;4789.425268967746;5535.640183388067;5777.2200752897825;5794.5364370851075;5822.272215973004;5308.761105750174;5576.414476479911;5715.8845631145505;5825.970870145622;5857.209276747755;4493.618042466296;4500.694798508396;5694.886102278014;5720.3707592164;5816.156657284585 05:45:01 INFO - PROCESS | 1657 | |5;yui.html;562.8545909075033;662.1888186970962;668.8261052126447;679.6397909108174;809.9352051835851;15262.694746105086;16245.187740612952;16265.26806293718;16281.592960175987;16299;14564.563063108124;14643.707125857476;14668.486602968898;14686.192259425727;14716.779248311284;652.7552167937024;681.5263391942602;820.9302209312201;844.1558441558442;844.6621351459404;802.7391097893179;831.1148626712531;848.3298194426395;850.6809946270149;852.8081181734091;911.5123408976199;912.726182145357;913.1872633356318;914.1681070226095;916.079340263038;885.7962668586262;899.3749344205779;900.9009009009004;901.9368644194909;902.1835239108617;585.1895125251515;630.741396027631;667.219353356574;668.1447746883996;668.2615709640826;12201;12232.694182645417;14922.030068045542;14928.25358732059;14930.32813523394;10641.57433702651;14318.498852540111;14352.425902963869;14513.27433628325;14939.626509337244;10966.780664386668;11588.188826782129;11676.47455864488;11752.823707644393;11757.294562326288;4503.932441013388;4554.521775213621;4658.114958157973;4739.265413860857;4767.451743049524;111858.44070779675;113228.43385783018;114123.42938285256;114890.42554787172;118777.62444751144;9283.953580232055;9982.650607228712;10663.418384412313;10720.839187412195;11226.550937962553;19359.806401936083;20435.795642043686;20652.483687907923;20708.689369659467;20724;7792.337651299703;8011.639476223584;8153.1439198884445;8269.710560130367;8279.172082791672;3678.6873115784924;4545.409096817459;4567.840125595588;4577.061702351077;4631.027484228282;3590.3178396105177;3675.61406052366;3692.9105913755507;3708.9629103708617;3717.553893532793;673.4275865514273;688.0951548713426;688.8277930517371;698.8637215742983;705.4567982653328;495.7347818916862;627.6736097229415;643.6813777180232;689.7689274093235;699.174973531238;8197.385196110316;8787.90151231096;8987.68925712064;9473.768410106642;9496.762580935394;598.2611474828583;649.5453182771984;663.2074670768385;668.9732410703615;674.639068098565;3585.6831782826075;4586.587207151372;4593.173228818845;4624.30635404692;4642.3500709900645;134576;136616;139754;139971;141539.29230353783;120674.3966280163;124431;124852;128253;128727;98239.50880245696;107834;113691;116820;120599.79400205886;9504.0020797818;9506.95246523763;9691.370060946016;9742.123208911233;9837.950810245902;60249;62572.37427625847;63132.05301920383;64386.03420948783;66250.33749662635;63525.68237158785;65331.346686532524;85765.98371512954;88083;98150;13056.738865222833;13111.14777539457;13187.868121319048;13201.20792752438;13808.792868106786;11920.225185362924;14705;15024.774628380364;15274.923625381802;15582.532524024298;13548.661283467993;13662.590122296348;13830.170189788645;14069.944754143686;14313.928430357782;10782.460876955964;13731.519396821062;13760.31198440054;13789.724205515633;14252.786208206679;13187.934060329637;13292.933535332262;13818.309084545934;13887.097338672955;13908.865775136206;11177.776444471228;11217.71955701114;11281.548738050386;11586.188966772244;11656.251562265728;8527.957360213159;9229.215516681157;10104.848427273744;10327.845082323622;10347.84478232812 05:45:01 INFO - PROCESS | 1657 | __end_tp_report 05:45:01 INFO - PROCESS | 1657 | __start_cc_report 05:45:01 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,358 05:45:01 INFO - PROCESS | 1657 | __end_cc_report 05:45:01 INFO - PROCESS | 1657 | __startTimestamp1474634701212__endTimestamp 05:45:01 INFO - PROCESS | 1657 | 05:45:01 INFO - PROCESS | 1657 | ------- Summary: start ------- 05:45:01 INFO - PROCESS | 1657 | Number of tests: 6 05:45:01 INFO - PROCESS | 1657 | 05:45:01 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5606.28 Median:5883.56 stddev:696.59 (11.8%) stddev-sans-first:660.97 05:45:01 INFO - PROCESS | 1657 | Values: 2722.2 3342.9 3361.5 3368.0 3392.1 5521.0 5629.0 5629.2 5688.8 5692.1 5397.6 5559.1 5611.8 5643.2 5653.0 5104.8 5115.6 5223.5 5242.2 5246.4 5158.4 5228.6 5307.1 5314.3 5336.6 4290.2 5220.3 5235.8 5257.9 5261.6 4178.4 4964.9 5017.3 5046.9 5116.9 4346.8 4933.4 5057.7 5118.1 5127.0 4375.3 5662.3 5670.8 5702.8 5726.4 6058.1 6135.4 6142.4 6179.4 6217.8 6167.1 6168.8 6182.1 6188.1 6192.4 5679.8 5872.5 5900.8 5915.3 5942.4 6142.9 6159.4 6197.8 6238.5 6289.8 5059.9 6109.1 6134.5 6145.2 6148.9 6194.4 6206.9 6207.7 6211.0 6218.0 4924.5 5972.6 6096.6 6131.7 6176.2 6119.2 6120.4 6179.8 6202.2 6208.5 4796.4 4881.9 4899.6 4901.3 4944.2 4350.4 4355.3 4381.4 4389.6 4411.3 5518.0 5853.0 5862.9 5908.3 5949.5 6158.9 6175.3 6181.4 6195.1 6195.9 5669.1 5688.3 5803.1 5831.1 5891.7 5365.7 5367.6 5395.2 5401.8 5439.2 6175.8 6219.5 6239.6 6282.0 6293.5 5366.4 6127.9 6135.5 6207.0 6216.3 5933.3 6130.0 6164.5 6214.8 6221.3 6047.4 6055.8 6064.5 6072.7 6079.9 5311.6 6087.7 6104.0 6119.5 6152.1 6107.5 6117.0 6125.9 6133.3 6153.9 5368.2 5998.3 6103.0 6115.4 6127.3 4334.0 4444.4 4784.8 5629.0 5883.6 4936.5 5808.8 6083.3 6111.1 6125.6 4953.0 4956.8 5961.8 5972.3 5979.0 05:45:01 INFO - PROCESS | 1657 | 05:45:01 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:68760.80 Median:22142.14 stddev:154004.61 (695.5%) stddev-sans-first:154351.20 05:45:01 INFO - PROCESS | 1657 | Values: 435.9 486.0 525.3 526.9 536.0 40648.0 42018.4 42136.2 42254.2 42293.0 43047.8 43335.3 43463.6 43573.3 43627.6 9739.3 9940.5 9944.4 9959.7 9969.1 9853.1 9954.9 9958.4 9961.0 9990.3 9909.8 9943.3 9959.5 9961.1 9962.0 8477.0 9900.5 9938.9 9950.5 9963.7 9745.2 9799.3 9809.4 9821.2 9830.5 40614.2 40821.8 40904.6 41060.0 41067.4 3162.9 3420.6 3704.9 3903.9 4035.9 30317.9 30849.5 31081.7 31103.4 31818.5 3261.7 3285.1 3285.6 3355.3 3389.5 451206.0 460892.0 463374.0 464633.0 466324.7 32841.7 59279.0 59489.7 59521.0 59694.4 33499.0 34518.8 34777.3 35034.1 35164.8 14363.8 14479.7 14510.0 14511.3 14517.6 12139.6 12232.2 12232.4 12264.5 12294.8 7749.3 7763.7 7764.3 7800.1 7817.3 3231.5 3241.8 3253.2 3253.3 3274.3 916.7 971.6 1000.8 1023.8 1026.1 24459.4 29983.8 30076.4 30147.9 30339.7 906.9 1045.9 1057.5 1096.4 1102.5 13492.6 13942.6 14166.4 14374.9 15662.0 672314.0 681678.0 683697.0 683763.0 684835.0 57528.0 57981.4 58065.0 58118.7 58230.7 1292.8 1311.9 1349.1 1354.1 1357.6 26940.7 29712.9 29905.9 30355.4 30587.0 50235.0 50755.5 50758.0 50814.7 50917.0 547089.3 560597.0 563557.0 564846.0 565037.0 26219.2 26406.7 27052.1 27239.3 27401.0 47369.3 47822.8 47970.8 48079.3 48827.5 38909.0 38944.0 39069.0 40248.4 40883.0 31673.9 37775.6 38853.6 39958.0 40212.6 37320.4 37714.6 38206.2 38610.6 38807.8 27281.0 28640.4 30539.7 34361.8 34794.3 25494.0 25605.6 25784.5 26146.0 26405.5 05:45:01 INFO - PROCESS | 1657 | 05:45:01 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:33113.99 Median:20838.53 stddev:90326.19 (433.5%) stddev-sans-first:90546.12 05:45:01 INFO - PROCESS | 1657 | Values: 370.5 376.6 385.4 391.2 404.7 18718.5 20843.3 20879.8 21005.3 21202.8 20252.6 20482.9 20961.7 21059.4 21253.0 5885.0 5922.5 6066.1 6155.4 6169.3 4154.1 4381.6 5895.5 6026.0 6130.1 6682.3 6808.8 6819.6 6825.3 6835.6 6529.5 6756.7 6799.5 6825.1 6830.4 5087.5 5379.1 5856.2 5905.4 5905.6 13051.9 13111.7 13362.6 13366.3 16867.6 1633.3 1864.6 1866.8 2000.7 2062.6 10004.9 10035.9 10050.1 10259.6 10414.2 1827.7 1837.0 1912.1 1920.5 1921.3 91752.0 110729.4 120078.8 120390.0 120690.0 11037.1 11135.3 11481.8 11528.4 11667.3 14158.9 14223.4 14272.6 14286.4 14337.9 8025.9 8276.0 8318.3 8395.1 8399.1 8153.0 8159.6 8190.3 8294.8 8298.5 3878.8 4296.0 4383.9 4432.2 4449.4 1591.2 1685.7 1689.4 1905.9 1972.0 740.7 777.7 920.8 960.6 1004.8 15480.5 19900.0 20531.9 20833.8 21077.0 888.2 918.3 989.4 1004.7 1008.0 10751.0 10874.5 10975.4 11021.2 12022.0 529500.0 533822.0 538226.0 539980.0 541037.0 121889.0 128650.0 129773.4 136845.0 145527.0 790.9 996.3 1051.4 1052.4 1058.7 17696.8 18016.7 18981.7 19020.0 19175.9 55194.0 55478.9 55968.7 56374.4 56652.4 79910.0 80812.6 81525.6 81850.0 82890.6 6577.4 6961.5 7255.6 8243.3 8250.2 10579.5 12684.2 12877.0 13381.1 13555.8 10348.4 10933.8 11213.3 11359.1 11441.7 11094.9 11204.7 11242.0 11284.8 11402.3 10957.9 11270.5 11544.5 11616.5 11669.6 6690.6 8144.2 8213.8 8296.7 8308.7 9202.9 11240.7 11249.6 11280.2 11357.9 05:45:01 INFO - PROCESS | 1657 | 05:45:01 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5580.80 Median:4827.37 stddev:3165.66 (65.6%) stddev-sans-first:3158.90 05:45:01 INFO - PROCESS | 1657 | Values: 1393.5 1510.4 1670.6 1718.2 1862.5 7889.9 8109.4 8241.0 8877.2 8885.4 8318.5 8586.3 8603.9 8726.4 8780.8 2352.4 2373.9 2382.3 2386.3 2390.2 1987.8 2341.2 2364.1 2384.9 2386.3 2350.5 2357.0 2367.8 2379.8 2384.7 2336.7 2340.4 2358.0 2359.2 2363.4 2351.5 2357.2 2362.7 2363.2 2367.6 7578.5 8612.2 8664.9 8856.5 8878.6 4955.5 5045.2 5254.1 5276.8 5299.4 3429.4 3621.2 3621.3 3630.9 3654.7 1034.8 1061.8 1070.1 1090.6 1093.4 9895.2 11141.3 11465.7 11595.1 11624.8 4467.8 4644.4 4738.1 4744.1 4755.9 9825.6 9928.2 9932.2 9932.8 9935.9 5286.3 5290.0 5291.7 5305.6 5312.3 4317.1 4463.4 4635.3 4768.0 4842.0 3224.4 3317.8 3801.0 3914.9 3944.5 1282.5 1291.5 1291.7 1298.9 1301.5 761.4 885.5 895.6 912.7 913.3 5544.4 5650.2 5829.6 5861.3 5928.2 422.4 440.9 441.4 442.4 930.0 3735.9 3900.4 3933.6 3936.2 4025.7 10457.2 10670.2 10779.5 10789.1 10794.5 10730.4 10734.5 10744.0 10764.1 10770.2 8086.9 8164.2 8169.3 8194.0 8198.7 4468.9 5443.4 5583.0 5677.5 5752.1 9457.3 9797.8 9806.9 9827.7 9848.7 9800.4 9889.8 9914.5 10079.9 10156.6 6820.9 6832.5 7023.8 7328.3 7503.9 6665.7 8317.1 8327.3 8329.8 8350.6 7302.3 7426.5 7478.1 7485.2 7570.5 7208.2 7269.4 7302.5 7339.9 7340.5 5871.8 6960.0 7024.2 7028.2 7033.1 4366.0 4690.0 4825.9 4869.0 4917.6 3711.4 4111.5 4787.1 4828.8 4892.7 05:45:01 INFO - PROCESS | 1657 | 05:45:01 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4633.21 Median:5183.51 stddev:1505.41 (29.0%) stddev-sans-first:1474.15 05:45:01 INFO - PROCESS | 1657 | Values: 304.3 309.8 311.9 315.8 316.4 4628.5 4682.6 4859.8 5194.5 5256.7 4406.4 4986.5 5223.3 5376.3 5420.6 3937.8 4007.6 4033.4 4089.8 4132.2 4105.8 4120.2 4148.3 4157.7 4180.5 3392.7 3697.4 3931.3 4067.9 4170.6 3241.0 3816.9 3990.6 4098.9 4170.6 3047.3 3354.8 3362.8 3528.9 3562.2 4707.5 4982.9 5025.5 5030.0 5387.7 3952.9 4021.0 4127.9 4648.1 4728.7 4774.7 5057.6 5506.3 5683.4 5760.9 3401.7 3909.6 3915.9 3919.1 3928.2 5003.5 5384.0 6047.7 6112.3 6155.9 3293.6 3883.0 3930.6 3943.7 3948.8 3310.4 3922.9 3992.0 4024.0 4029.4 3710.6 4001.5 4705.9 4706.2 4725.5 5364.4 5604.9 5648.9 5851.3 5881.9 1190.4 1347.8 1366.3 1371.7 1380.9 699.8 797.7 823.2 837.5 846.5 4177.7 5215.6 5520.3 5553.7 5559.7 5980.3 6057.7 6060.2 6091.0 6114.0 4583.6 5172.6 5468.0 5533.3 5533.6 2436.0 2764.6 2941.9 3170.4 3170.8 6230.0 6261.7 6309.5 6313.7 6315.9 5730.4 6125.6 6145.9 6171.3 6236.9 5470.6 6292.7 6302.8 6307.8 6389.2 4884.6 5621.9 6052.3 6064.0 6115.8 5462.9 5760.6 5968.3 5971.6 6007.1 5864.3 6299.2 6317.7 6322.6 6337.6 3352.9 3907.9 3965.4 3981.7 4000.2 5267.3 5873.2 6098.9 6107.7 6123.7 4523.9 4655.7 5579.1 5929.4 5940.2 5801.5 5861.6 5886.8 5907.9 5969.0 4789.4 5535.6 5777.2 5794.5 5822.3 5308.8 5576.4 5715.9 5826.0 5857.2 4493.6 4500.7 5694.9 5720.4 5816.2 05:45:01 INFO - PROCESS | 1657 | 05:45:01 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:24283.95 Median:3713.26 stddev:38449.70 (1035.5%) stddev-sans-first:38516.31 05:45:01 INFO - PROCESS | 1657 | Values: 562.9 662.2 668.8 679.6 809.9 15262.7 16245.2 16265.3 16281.6 16299.0 14564.6 14643.7 14668.5 14686.2 14716.8 652.8 681.5 820.9 844.2 844.7 802.7 831.1 848.3 850.7 852.8 911.5 912.7 913.2 914.2 916.1 885.8 899.4 900.9 901.9 902.2 585.2 630.7 667.2 668.1 668.3 12201.0 12232.7 14922.0 14928.3 14930.3 10641.6 14318.5 14352.4 14513.3 14939.6 10966.8 11588.2 11676.5 11752.8 11757.3 4503.9 4554.5 4658.1 4739.3 4767.5 111858.4 113228.4 114123.4 114890.4 118777.6 9284.0 9982.7 10663.4 10720.8 11226.6 19359.8 20435.8 20652.5 20708.7 20724.0 7792.3 8011.6 8153.1 8269.7 8279.2 3678.7 4545.4 4567.8 4577.1 4631.0 3590.3 3675.6 3692.9 3709.0 3717.6 673.4 688.1 688.8 698.9 705.5 495.7 627.7 643.7 689.8 699.2 8197.4 8787.9 8987.7 9473.8 9496.8 598.3 649.5 663.2 669.0 674.6 3585.7 4586.6 4593.2 4624.3 4642.4 134576.0 136616.0 139754.0 139971.0 141539.3 120674.4 124431.0 124852.0 128253.0 128727.0 98239.5 107834.0 113691.0 116820.0 120599.8 9504.0 9507.0 9691.4 9742.1 9838.0 60249.0 62572.4 63132.1 64386.0 66250.3 63525.7 65331.3 85766.0 88083.0 98150.0 13056.7 13111.1 13187.9 13201.2 13808.8 11920.2 14705.0 15024.8 15274.9 15582.5 13548.7 13662.6 13830.2 14069.9 14313.9 10782.5 13731.5 13760.3 13789.7 14252.8 13187.9 13292.9 13818.3 13887.1 13908.9 11177.8 11217.7 11281.5 11586.2 11656.3 8528.0 9229.2 10104.8 10327.8 10347.8 05:45:01 INFO - PROCESS | 1657 | -------- Summary: end -------- 05:45:01 INFO - PROCESS | 1657 | 05:45:01 INFO - TEST-INFO | 1657: exit 0 05:45:01 INFO - TEST-OK | dromaeo_css | took 1113008ms 05:45:01 INFO - TEST-START | kraken 05:45:01 INFO - operating with platform_type : mac_ 05:45:01 INFO - Initialising browser for kraken test... 05:45:01 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpq4uDUe/profile http://localhost:49269/getInfo.html) 05:45:04 INFO - PROCESS | 1667 | 2016-09-23 05:45:04.588 plugin-container[1668:9844] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4323, name = 'com.apple.tsm.portname' 05:45:04 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:45:04 INFO - PROCESS | 1667 | 2016-09-23 05:45:04.592 plugin-container[1668:9844] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8617, name = 'com.apple.CFPasteboardClient' 05:45:04 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:45:04 INFO - PROCESS | 1667 | 2016-09-23 05:45:04.592 plugin-container[1668:9844] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:45:04 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 05:45:04 INFO - PROCESS | 1667 | colorDepth:24 05:45:04 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 05:45:04 INFO - PROCESS | 1667 | __metrics 05:45:04 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 05:45:05 INFO - TEST-INFO | 1667: exit 0 05:45:05 INFO - Browser initialized. 05:45:05 INFO - Running cycle 1/1 for kraken test... 05:45:05 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpq4uDUe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:45:07 INFO - PROCESS | 1669 | 2016-09-23 05:45:07.976 plugin-container[1670:9953] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8713, name = 'com.apple.tsm.portname' 05:45:07 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:45:07 INFO - PROCESS | 1669 | 2016-09-23 05:45:07.978 plugin-container[1670:9953] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e03, name = 'com.apple.CFPasteboardClient' 05:45:07 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:45:07 INFO - PROCESS | 1669 | 2016-09-23 05:45:07.978 plugin-container[1670:9953] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:45:17 INFO - PROCESS | 1669 | RSS: Main: 239505408 05:45:17 INFO - PROCESS | 1669 | 05:45:20 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection) 05:45:21 INFO - PROCESS | 1669 | RSS: Main: 253071360 05:45:21 INFO - PROCESS | 1669 | 05:45:24 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-dft) 05:45:25 INFO - PROCESS | 1669 | RSS: Main: 260571136 05:45:25 INFO - PROCESS | 1669 | 05:45:28 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-fft) 05:45:29 INFO - PROCESS | 1669 | RSS: Main: 261505024 05:45:29 INFO - PROCESS | 1669 | 05:45:31 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator) 05:45:31 INFO - PROCESS | 1669 | RSS: Main: 262721536 05:45:31 INFO - PROCESS | 1669 | 05:45:33 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:45:34 INFO - PROCESS | 1669 | RSS: Main: 264331264 05:45:34 INFO - PROCESS | 1669 | 05:45:37 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom) 05:45:38 INFO - PROCESS | 1669 | RSS: Main: 264900608 05:45:38 INFO - PROCESS | 1669 | 05:45:41 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate) 05:45:41 INFO - PROCESS | 1669 | RSS: Main: 265269248 05:45:41 INFO - PROCESS | 1669 | 05:45:44 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial) 05:45:45 INFO - PROCESS | 1669 | RSS: Main: 265191424 05:45:45 INFO - PROCESS | 1669 | 05:45:47 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:45:47 INFO - PROCESS | 1669 | RSS: Main: 265125888 05:45:47 INFO - PROCESS | 1669 | 05:45:49 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:45:50 INFO - PROCESS | 1669 | RSS: Main: 265244672 05:45:50 INFO - PROCESS | 1669 | 05:45:52 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:45:53 INFO - PROCESS | 1669 | RSS: Main: 265240576 05:45:53 INFO - PROCESS | 1669 | 05:45:56 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:45:56 INFO - PROCESS | 1669 | RSS: Main: 265535488 05:45:56 INFO - PROCESS | 1669 | 05:45:59 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:46:00 INFO - PROCESS | 1669 | RSS: Main: 265424896 05:46:00 INFO - PROCESS | 1669 | 05:46:02 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49269/tests/kraken/driver.html?testName=ai-astar) 05:46:02 INFO - PROCESS | 1669 | RSS: Main: 267026432 05:46:02 INFO - PROCESS | 1669 | 05:46:02 INFO - PROCESS | 1669 | __start_tp_report 05:46:02 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 05:46:02 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 05:46:02 INFO - PROCESS | 1669 | |i|pagename|runs| 05:46:02 INFO - PROCESS | 1669 | |0;ai-astar;114;119;118;116;118;116;116;116;116;116 05:46:02 INFO - PROCESS | 1669 | |1;audio-beat-detection;153;147;96;179;100;132;144;146;120;146 05:46:02 INFO - PROCESS | 1669 | |2;audio-dft;207;270;283;258;282;194;195;200;195;194 05:46:02 INFO - PROCESS | 1669 | |3;audio-fft;93;58;59;81;88;51;60;79;104;84 05:46:02 INFO - PROCESS | 1669 | |4;audio-oscillator;84;70;90;72;74;89;122;58;93;73 05:46:02 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;132;135;88;86;109;87;88;85;88;86 05:46:02 INFO - PROCESS | 1669 | |6;imaging-darkroom;143;152;95;98;136;166;96;95;125;128 05:46:02 INFO - PROCESS | 1669 | |7;imaging-desaturate;92;88;91;99;132;147;89;92;92;91 05:46:02 INFO - PROCESS | 1669 | |8;json-parse-financial;59;57;58;61;59;59;57;60;69;83 05:46:02 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;67;73;68;88;78;78;105;105;80;70 05:46:02 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;86;89;89;89;91;86;78;104;107;111 05:46:02 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;126;133;162;151;139;141;138;146;187;166 05:46:02 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;173;175;177;172;170;177;170;176;175;169 05:46:02 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;70;71;68;70;74;69;68;82;79;91 05:46:02 INFO - PROCESS | 1669 | __end_tp_report 05:46:02 INFO - PROCESS | 1669 | __start_cc_report 05:46:02 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,918 05:46:02 INFO - PROCESS | 1669 | __end_cc_report 05:46:02 INFO - PROCESS | 1669 | __startTimestamp1474634762416__endTimestamp 05:46:02 INFO - PROCESS | 1669 | 05:46:02 INFO - PROCESS | 1669 | ------- Summary: start ------- 05:46:02 INFO - PROCESS | 1669 | Number of tests: 14 05:46:02 INFO - PROCESS | 1669 | 05:46:02 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:116.50 Median:116.00 stddev:1.43 (1.2%) stddev-sans-first:1.20 05:46:02 INFO - PROCESS | 1669 | Values: 114.0 119.0 118.0 116.0 118.0 116.0 116.0 116.0 116.0 116.0 05:46:02 INFO - PROCESS | 1669 | 05:46:02 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:136.30 Median:146.50 stddev:25.14 (17.2%) stddev-sans-first:25.93 05:46:02 INFO - PROCESS | 1669 | Values: 153.0 147.0 96.0 179.0 100.0 132.0 144.0 146.0 120.0 146.0 05:46:02 INFO - PROCESS | 1669 | 05:46:02 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:227.80 Median:232.50 stddev:39.89 (17.2%) stddev-sans-first:41.59 05:46:02 INFO - PROCESS | 1669 | Values: 207.0 270.0 283.0 258.0 282.0 194.0 195.0 200.0 195.0 194.0 05:46:02 INFO - PROCESS | 1669 | 05:46:02 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:75.70 Median:80.00 stddev:17.66 (22.1%) stddev-sans-first:17.59 05:46:02 INFO - PROCESS | 1669 | Values: 93.0 58.0 59.0 81.0 88.0 51.0 60.0 79.0 104.0 84.0 05:46:02 INFO - PROCESS | 1669 | 05:46:02 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:82.50 Median:79.00 stddev:17.64 (22.3%) stddev-sans-first:18.70 05:46:02 INFO - PROCESS | 1669 | Values: 84.0 70.0 90.0 72.0 74.0 89.0 122.0 58.0 93.0 73.0 05:46:02 INFO - PROCESS | 1669 | 05:46:02 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:98.40 Median:86.50 stddev:19.78 (22.9%) stddev-sans-first:16.84 05:46:02 INFO - PROCESS | 1669 | Values: 132.0 135.0 88.0 86.0 109.0 87.0 88.0 85.0 88.0 86.0 05:46:02 INFO - PROCESS | 1669 | 05:46:02 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:123.40 Median:130.50 stddev:26.26 (20.1%) stddev-sans-first:26.88 05:46:02 INFO - PROCESS | 1669 | Values: 143.0 152.0 95.0 98.0 136.0 166.0 96.0 95.0 125.0 128.0 05:46:02 INFO - PROCESS | 1669 | 05:46:02 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:101.30 Median:91.50 stddev:20.65 (22.6%) stddev-sans-first:21.62 05:46:02 INFO - PROCESS | 1669 | Values: 92.0 88.0 91.0 99.0 132.0 147.0 89.0 92.0 92.0 91.0 05:46:02 INFO - PROCESS | 1669 | 05:46:02 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:62.20 Median:59.50 stddev:8.08 (13.6%) stddev-sans-first:8.49 05:46:02 INFO - PROCESS | 1669 | Values: 59.0 57.0 58.0 61.0 59.0 59.0 57.0 60.0 69.0 83.0 05:46:02 INFO - PROCESS | 1669 | 05:46:02 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:81.20 Median:75.50 stddev:14.02 (18.6%) stddev-sans-first:13.90 05:46:02 INFO - PROCESS | 1669 | Values: 67.0 73.0 68.0 88.0 78.0 78.0 105.0 105.0 80.0 70.0 05:46:02 INFO - PROCESS | 1669 | 05:46:02 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:87.50 stddev:10.62 (12.1%) stddev-sans-first:10.96 05:46:02 INFO - PROCESS | 1669 | Values: 86.0 89.0 89.0 89.0 91.0 86.0 78.0 104.0 107.0 111.0 05:46:02 INFO - PROCESS | 1669 | 05:46:02 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:148.90 Median:148.50 stddev:18.21 (12.3%) stddev-sans-first:17.33 05:46:02 INFO - PROCESS | 1669 | Values: 126.0 133.0 162.0 151.0 139.0 141.0 138.0 146.0 187.0 166.0 05:46:02 INFO - PROCESS | 1669 | 05:46:02 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.40 Median:175.00 stddev:3.03 (1.7%) stddev-sans-first:3.21 05:46:02 INFO - PROCESS | 1669 | Values: 173.0 175.0 177.0 172.0 170.0 177.0 170.0 176.0 175.0 169.0 05:46:02 INFO - PROCESS | 1669 | 05:46:02 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.20 Median:72.50 stddev:7.57 (10.4%) stddev-sans-first:7.87 05:46:02 INFO - PROCESS | 1669 | Values: 70.0 71.0 68.0 70.0 74.0 69.0 68.0 82.0 79.0 91.0 05:46:02 INFO - PROCESS | 1669 | -------- Summary: end -------- 05:46:02 INFO - PROCESS | 1669 | 05:46:03 INFO - TEST-INFO | 1669: exit 0 05:46:03 INFO - TEST-OK | kraken | took 61424ms 05:46:03 INFO - SUITE-END | took 1174s 05:46:03 INFO - Completed test suite (00:19:34) 05:46:03 INFO - javascript benchmark 05:46:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7189.052609972886, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2722.224166112658, 3342.882999095032, 3361.4789707595314, 3368.040108569057, 3392.1350055735797, 5521.033819081655, 5628.98678237917, 5629.183768353586, 5688.829335119953, 5692.117721753125, 5397.595180361469, 5559.054960656688, 5611.80358687446, 5643.238162848014, 5653.0, 5104.795808167669, 5115.641905066647, 5223.529882310591, 5242.187460943541, 5246.422893481733, 5158.406783219922, 5228.633995620308, 5307.124324486455, 5314.309139811839, 5336.573074154058, 4290.184864875695, 5220.269162317279, 5235.842924712246, 5257.921131183035, 5261.579073674096, 4178.373244013392, 4964.851054468371, 5017.2724954881705, 5046.924296135561, 5116.897662046776, 4346.826126954918, 4933.383327084114, 5057.671251368649, 5118.10433174193, 5127.0, 4375.299952007664, 5662.2639056922335, 5670.773169073233, 5702.800401985952, 5726.427357264281, 6058.1215723719815, 6135.385782596134, 6142.355052719445, 6179.443850053517, 6217.751289948396, 6167.105769663419, 6168.84577885552, 6182.1345011698395, 6188.1027251048345, 6192.380761923771, 5679.772809087673, 5872.530197584183, 5900.822975310747, 5915.349311575723, 5942.435468630473, 6142.877142457126, 6159.4456498915315, 6197.81406557804, 6238.532110091761, 6289.811305660837, 5059.924101138486, 6109.11417844419, 6134.539909506805, 6145.201123853871, 6148.907766383508, 6194.442500174916, 6206.875862482725, 6207.720652570645, 6211.0372892201785, 6217.9689101555105, 4924.507549245047, 5972.641641501437, 6096.634201947897, 6131.743695907034, 6176.227971503562, 6119.204503414528, 6120.387961203844, 6179.752809887555, 6202.224721909762, 6208.4722798562625, 4796.37647105874, 4881.877953051202, 4899.58353539946, 4901.264810278487, 4944.233643785219, 4350.434443522322, 4355.303151495746, 4381.38660587518, 4389.604935555815, 4411.316245981878, 5518.0, 5853.0, 5862.912056319158, 5908.261467316585, 5949.464548190597, 6158.938410615926, 6175.320714721467, 6181.381861813783, 6195.101710251988, 6195.876082478415, 5669.06460434032, 5688.317401911797, 5803.071508558611, 5831.096180092092, 5891.734871930774, 5365.678059316453, 5367.624266301322, 5395.163749618815, 5401.810936617199, 5439.184122381675, 6175.783847565313, 6219.502439804804, 6239.625622462667, 6281.96859015702, 6293.527985401021, 5366.3560372753955, 6127.908081378872, 6135.477655820289, 6207.0, 6216.2540495140875, 5933.347331793413, 6129.9701281150365, 6164.506839452923, 6214.8135555931585, 6221.346758590283, 6047.365026672312, 6055.848603784941, 6064.48451881595, 6072.72672729737, 6079.878402431838, 5311.575073993994, 6087.695615219132, 6104.004449388181, 6119.502553941335, 6152.138700581832, 6107.480864126422, 6117.049106809072, 6125.876495711566, 6133.26400831903, 6153.876922461615, 5368.221607866915, 5998.25021872266, 6102.9694851525455, 6115.3578874217565, 6127.295361033538, 4333.956660433355, 4444.422225110844, 4784.808607655794, 5628.971855140698, 5883.558733094949, 4936.481669424802, 5808.767649293981, 6083.270007599116, 6111.1444397785235, 6125.632272693224, 4952.950470495393, 4956.801727930987, 5961.791337303173, 5972.313183983897, 5978.983572792548], "value": 5562.732039585223, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.89935413081963, 485.9600455026242, 525.2934802690385, 526.8577484079299, 536.0458384076345, 40648.0, 42018.36972445412, 42136.15727685444, 42254.15491690168, 42293.0, 43047.78476107615, 43335.34996975048, 43463.565364346345, 43573.34639980403, 43627.56372436275, 9739.269554783385, 9940.502974851264, 9944.353617014907, 9959.651412200574, 9969.10278074973, 9853.06395892389, 9954.850677239847, 9958.352707074017, 9960.950195249014, 9990.250731195154, 9909.801803963916, 9943.303968722192, 9959.452230127341, 9961.103500684936, 9961.953994830546, 8476.957615211946, 9900.50497475127, 9938.90061099387, 9950.502474876263, 9963.651272205438, 9745.171660408874, 9799.265055120895, 9809.411435313832, 9821.214302855755, 9830.508474576314, 40614.187716245804, 40821.79589102065, 40904.590954090374, 41060.0, 41067.38398923989, 3162.908796465216, 3420.640832712579, 3704.9259014819554, 3903.945934597643, 4035.878923632296, 30317.93887213937, 30849.537256941163, 31081.689183108105, 31103.37793244145, 31818.522722159185, 3261.7390608751243, 3285.1130194847497, 3285.572875526166, 3355.3457075870215, 3389.49157626358, 451206.0, 460892.0, 463374.0, 464633.0, 466324.66837665933, 32841.67158328386, 59279.0, 59489.70255148697, 59521.0, 59694.40305596975, 33499.0, 34518.82740586281, 34777.30445391129, 35034.12414689602, 35164.82417587947, 14363.784543231859, 14479.710405791826, 14510.0, 14511.274436278354, 14517.637059073608, 12139.575114870937, 12232.204906681038, 12232.449539770732, 12264.509419623293, 12294.75410491803, 7749.263819937097, 7763.650635721406, 7764.288447490254, 7800.141984381714, 7817.335526480313, 3231.5475833383343, 3241.7730758846656, 3253.170441537393, 3253.2842774589553, 3274.279658475132, 916.6883259691737, 971.577363846729, 1000.849872519128, 1023.82083135451, 1026.1431704526653, 24459.38851528726, 29983.800647973836, 30076.398472030436, 30147.944821931127, 30339.69660303413, 906.909309069101, 1045.9163266938626, 1057.5346847387125, 1096.4408151842617, 1102.4873433853136, 13492.59522214335, 13942.58172254834, 14166.433342666383, 14374.928125359307, 15662.0, 672314.0, 681678.0, 683697.0, 683763.0, 684835.0, 57528.0, 57981.4201857976, 58065.0, 58118.7094064527, 58230.7088464555, 1292.7802273613509, 1311.888489478405, 1349.0826238157952, 1354.1130559483356, 1357.5835013675674, 26940.730592694606, 29712.851435742683, 29905.850470747508, 30355.392892142474, 30587.0, 50235.0, 50755.49244507508, 50758.0, 50814.74592627013, 50917.0, 547089.2645536747, 560597.0, 563557.0, 564846.0, 565037.0, 26219.21342359732, 26406.73593264043, 27052.05317813867, 27239.31901702394, 27401.0, 47369.28946065881, 47822.76088619535, 47970.80072998203, 48079.27881081856, 48827.51172488372, 38909.02727431837, 38944.02639934024, 39069.0, 40248.3962740565, 40883.0, 31673.891413800407, 37775.62224377721, 38853.640122596706, 39958.0, 40212.59787402205, 37320.44019339658, 37714.622853772205, 38206.235875282895, 38610.613893861824, 38807.80596097114, 27281.0, 28640.42719145647, 30539.694603053686, 34361.82819085989, 34794.304113918086, 25493.958564447064, 25605.615915760907, 25784.484310314063, 26146.0, 26405.471890561697], "value": 18657.60222024892, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.53312825839436, 376.61208954776583, 385.3814627522825, 391.21561269654336, 404.7086098009434, 18718.53203669907, 20843.27048553299, 20879.791202087974, 21005.264815731472, 21202.787972120273, 20252.59494810103, 20482.897585512088, 20961.685574716354, 21059.368218953456, 21253.0, 5884.970575147118, 5922.526197904179, 6066.120412540179, 6155.415235552616, 6169.321374648807, 4154.127633197032, 4381.561843815625, 5895.528357731371, 6025.969870150665, 6130.141780150782, 6682.264950855402, 6808.761693340734, 6819.624920629363, 6825.31746825316, 6835.58986460814, 6529.5429319947625, 6756.6621668916605, 6799.4900382471515, 6825.1127353443735, 6830.351116643936, 5087.516685914832, 5379.112446446371, 5856.180134781134, 5905.379935106795, 5905.557083218776, 13051.869481305255, 13111.672208194872, 13362.599122026355, 13366.264855433044, 16867.578310542212, 1633.2895190592012, 1864.6457173137196, 1866.8332292317302, 2000.6795514960202, 2062.577171679802, 10004.94997525015, 10035.899641003569, 10050.145737612313, 10259.589616415335, 10414.16686665065, 1827.7349784281337, 1837.0, 1912.1204246046834, 1920.5486710622993, 1921.28912302449, 91752.0, 110729.44635276771, 120078.79921200764, 120390.0, 120690.0, 11037.061849742862, 11135.33188008722, 11481.770364592661, 11528.423578821026, 11667.299962002136, 14158.858411415755, 14223.431062757581, 14272.571822845332, 14286.357113929898, 14337.928310358382, 8025.919740802634, 8276.048254450816, 8318.292945099613, 8395.118512556215, 8399.11809260031, 8153.0, 8159.592020398956, 8190.303824174893, 8294.83410331802, 8298.543580103153, 3878.8448462061765, 4296.0, 4383.890402739957, 4432.246518091933, 4449.377087207794, 1591.1964457948661, 1685.6881476926808, 1689.4340395967488, 1905.9047047647562, 1971.9704204436946, 740.6889106575252, 777.6772639354605, 920.802027564077, 960.5629438605417, 1004.754104909921, 15480.458183963732, 19900.0, 20531.897340513202, 20833.791662083186, 21077.0, 888.1784349476837, 918.3479729392072, 989.3618615992644, 1004.6734811186394, 1008.0, 10751.0, 10874.456277186267, 10975.396353200491, 11021.173411993972, 12022.0, 529500.0, 533822.0, 538226.0, 539980.0, 541037.0, 121889.0, 128650.0, 129773.35113324373, 136845.0, 145527.0, 790.9288164065147, 996.2627655534852, 1051.3796859852723, 1052.4211683574003, 1058.655936820521, 17696.823031769516, 18016.729749053513, 18981.71527427062, 19020.0, 19175.945323007367, 55194.0, 55478.89042219214, 55968.72015639896, 56374.436255636916, 56652.433475664715, 79910.0, 80812.59593702228, 81525.59237203776, 81850.0, 82890.58554707188, 6577.375149360803, 6961.477889158232, 7255.637218139177, 8243.340532757486, 8250.174982501701, 10579.4710264488, 12684.23894566329, 12877.034222433167, 13381.130226535244, 13555.79666305026, 10348.379097254188, 10933.83599246028, 11213.271137376045, 11359.148063895076, 11441.713957151138, 11094.889051109385, 11204.719882003015, 11242.044426223863, 11284.774304514029, 11402.315861048364, 10957.945210273898, 11270.549178032787, 11544.538218471167, 11616.535338586362, 11669.649910502698, 6690.632015239208, 8144.22629850187, 8213.835723285381, 8296.70961516344, 8308.70919517814, 9202.89565252174, 11240.718982025515, 11249.606263780728, 11280.153988451064, 11357.886421136014], "value": 9330.073348907967, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1393.5401317565204, 1510.3505492638162, 1670.6157583755744, 1718.1924495487117, 1862.5436767991853, 7889.921100788991, 8109.351251899849, 8240.958795206023, 8877.201051905318, 8885.378023538355, 8318.459300145487, 8586.31309495239, 8603.91396086039, 8726.38915275931, 8780.780480487974, 2352.4354155002807, 2373.8575685458927, 2382.309130352196, 2386.343755467252, 2390.247072172269, 1987.840972722186, 2341.1571834139695, 2364.0898254172093, 2384.856908585482, 2386.319898828834, 2350.4711439925964, 2357.033616217351, 2367.7750613691674, 2379.773921477457, 2384.7496012918655, 2336.7312759032757, 2340.3564019894493, 2358.0, 2359.1860808021206, 2363.4327761337327, 2351.459164392182, 2357.2103345379146, 2362.65741467488, 2363.1610778173845, 2367.6448532720055, 7578.522188173253, 8612.181842724995, 8664.913350866475, 8856.51289179095, 8878.556072196365, 4955.5044495550155, 5045.243213517965, 5254.133068043769, 5276.7889284428575, 5299.390570084451, 3429.3827111120236, 3621.2214373909737, 3621.2938476996997, 3630.8910732678064, 3654.6528079832374, 1034.8085604163255, 1061.7611037516574, 1070.0904231403322, 1090.5801266512372, 1093.4423444043516, 9895.158911492532, 11141.275817071864, 11465.65603031902, 11595.130365222558, 11624.825627615592, 4467.843625473093, 4644.419447569054, 4738.147133515931, 4744.122337367598, 4755.881102972384, 9825.606975720892, 9928.205743540499, 9932.205423566098, 9932.80134397308, 9935.850962235572, 5286.286351342596, 5290.0, 5291.682499049993, 5305.575553955674, 5312.282841816305, 4317.050248945227, 4463.419755431773, 4635.258358662665, 4767.998720268783, 4842.007388485352, 3224.4196044712176, 3317.834108294576, 3801.0497375656087, 3914.8629797956933, 3944.467496887941, 1282.499825068224, 1291.4834066373526, 1291.6706239908951, 1298.9285589292679, 1301.4859130643342, 761.417515600566, 885.4687187687465, 895.6193617712577, 912.7216199059218, 913.3150137396952, 5544.445555444504, 5650.152477128464, 5829.562782791308, 5861.325947516005, 5928.199693041471, 422.4128461438603, 440.88757366871886, 441.41292081531486, 442.4314755539117, 929.9636686219499, 3735.906602334909, 3900.414937759359, 3933.645971862546, 3936.232434675268, 4025.6980726445636, 10457.215708821868, 10670.199735019905, 10779.544761457102, 10789.136869050457, 10794.514246858911, 10730.409827459409, 10734.516946737416, 10744.033037026706, 10764.085052770446, 10770.24608277413, 8086.878696819553, 8164.183581641788, 8169.346452283803, 8193.95903020493, 8198.672053117927, 4468.865934021985, 5443.428440013741, 5582.995060889079, 5677.51741102011, 5752.108423194411, 9457.29070319715, 9797.755056123653, 9806.85289720669, 9827.705168844946, 9848.655297064568, 9800.411975281506, 9889.802203955736, 9914.454704991007, 10079.949600251952, 10156.644517441853, 6820.93179068203, 6832.487563432663, 7023.824404389931, 7328.3404493595835, 7503.88744168827, 6665.733370665119, 8317.085120636604, 8327.333813294801, 8329.791755206168, 8350.58247087631, 7302.306280903305, 7426.517276377018, 7478.06524184477, 7485.214052524407, 7570.507916985378, 7208.171060328129, 7269.418446524372, 7302.452316076422, 7339.889901651372, 7340.5228660139055, 5871.794487192928, 6960.0, 7024.1571011478945, 7028.191757947901, 7033.085698859116, 4365.978170109256, 4689.953100468952, 4825.9034819304115, 4869.0, 4917.606591472745, 3711.406175011985, 4111.465509483867, 4787.114383839001, 4828.830990915301, 4892.730899800546], "value": 4435.152077425907, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.3486937952781, 309.8466259201696, 311.8846026970021, 315.76633291364385, 316.38621075114264, 4628.514006029369, 4682.57856792889, 4859.833639926419, 5194.454582268864, 5256.737163141842, 4406.413691071211, 4986.4514903360605, 5223.268742376061, 5376.301080859483, 5420.5934554908345, 3937.8424863005516, 4007.5792041835625, 4033.35466325388, 4089.795510224492, 4132.173565286939, 4105.794710264475, 4120.237756015147, 4148.336266197409, 4157.708960372775, 4180.47744031996, 3392.7285817134693, 3697.4268988306853, 3931.2923673738715, 4067.8677418387824, 4170.562090980449, 3241.0, 3816.9045773855596, 3990.6009399060154, 4098.918021639565, 4170.582941705836, 3047.2686555226805, 3354.7987120772586, 3362.7557803612576, 3528.9294214115575, 3562.1985053363046, 4707.5057119002695, 4982.9252561211615, 5025.497450254945, 5030.0, 5387.703676297802, 3952.9011774705573, 4020.994751312172, 4127.9174416511505, 4648.093621743761, 4728.71627702339, 4774.713517188945, 5057.645964782467, 5506.256655351556, 5683.431656834325, 5760.855978600526, 3401.676840700154, 3909.6285852843935, 3915.941260881089, 3919.060065267682, 3928.194720082376, 5003.5246651568405, 5383.973080134574, 6047.727852246616, 6112.297085835142, 6155.907661385038, 3293.604767427924, 3883.0, 3930.6265904739003, 3943.743656662308, 3948.782816945038, 3310.4372256716415, 3922.882313530599, 3991.960080399217, 4023.959760402417, 4029.395590661424, 3710.6289371062676, 4001.4998125234347, 4705.85882423521, 4706.223473126895, 4725.503822098699, 5364.383095943938, 5604.943950560524, 5648.943510564842, 5851.327097383855, 5881.882362352729, 1190.4047976011993, 1347.786991707471, 1366.2827015816576, 1371.739369519808, 1380.9090818253467, 699.7620808924888, 797.6769408389612, 823.1644880446352, 837.5477242289096, 846.530175752451, 4177.665786737054, 5215.608829337739, 5520.316303527382, 5553.694546799884, 5559.663462499177, 5980.252468441445, 6057.666828324396, 6060.212172417558, 6091.02543593023, 6114.0, 4583.633309335245, 5172.560332371715, 5468.0, 5533.335999680065, 5533.6403133796175, 2435.9756402436105, 2764.557670772667, 2941.896788704236, 3170.4134735073626, 3170.8414579270943, 6229.96885015572, 6261.7182226799905, 6309.49524038085, 6313.652749098752, 6315.873682526324, 5730.398308177581, 6125.621735109565, 6145.877082458326, 6171.290301615282, 6236.906446403307, 5470.577649811078, 6292.748290068529, 6302.779402720883, 6307.779227727099, 6389.2013498313645, 4884.633652476007, 5621.8594535136945, 6052.303985041601, 6063.969680151571, 6115.798312607547, 5462.863428414322, 5760.588655779361, 5968.283805943314, 5971.641701497924, 6007.098935159761, 5864.296284445893, 6299.212598425197, 6317.747290108344, 6322.620642761265, 6337.6197428154455, 3352.9497057544645, 3907.94832712828, 3965.4448377226636, 3981.701372397024, 4000.199860097885, 5267.2889159962915, 5873.207117039145, 6098.878022439438, 6107.6868473278455, 6123.724432400463, 4523.883266227717, 4655.674102812771, 5579.130991118021, 5929.377415371324, 5940.16837642739, 5801.535877129904, 5861.563916840401, 5886.82035754619, 5907.881842363215, 5968.955432799329, 4789.425268967746, 5535.640183388067, 5777.2200752897825, 5794.5364370851075, 5822.272215973004, 5308.761105750174, 5576.414476479911, 5715.8845631145505, 5825.970870145622, 5857.209276747755, 4493.618042466296, 4500.694798508396, 5694.886102278014, 5720.3707592164, 5816.156657284585], "value": 4149.945832371381, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.8545909075033, 662.1888186970962, 668.8261052126447, 679.6397909108174, 809.9352051835851, 15262.694746105086, 16245.187740612952, 16265.26806293718, 16281.592960175987, 16299.0, 14564.563063108124, 14643.707125857476, 14668.486602968898, 14686.192259425727, 14716.779248311284, 652.7552167937024, 681.5263391942602, 820.9302209312201, 844.1558441558442, 844.6621351459404, 802.7391097893179, 831.1148626712531, 848.3298194426395, 850.6809946270149, 852.8081181734091, 911.5123408976199, 912.726182145357, 913.1872633356318, 914.1681070226095, 916.079340263038, 885.7962668586262, 899.3749344205779, 900.9009009009004, 901.9368644194909, 902.1835239108617, 585.1895125251515, 630.741396027631, 667.219353356574, 668.1447746883996, 668.2615709640826, 12201.0, 12232.694182645417, 14922.030068045542, 14928.25358732059, 14930.32813523394, 10641.57433702651, 14318.498852540111, 14352.425902963869, 14513.27433628325, 14939.626509337244, 10966.780664386668, 11588.188826782129, 11676.47455864488, 11752.823707644393, 11757.294562326288, 4503.932441013388, 4554.521775213621, 4658.114958157973, 4739.265413860857, 4767.451743049524, 111858.44070779675, 113228.43385783018, 114123.42938285256, 114890.42554787172, 118777.62444751144, 9283.953580232055, 9982.650607228712, 10663.418384412313, 10720.839187412195, 11226.550937962553, 19359.806401936083, 20435.795642043686, 20652.483687907923, 20708.689369659467, 20724.0, 7792.337651299703, 8011.639476223584, 8153.1439198884445, 8269.710560130367, 8279.172082791672, 3678.6873115784924, 4545.409096817459, 4567.840125595588, 4577.061702351077, 4631.027484228282, 3590.3178396105177, 3675.61406052366, 3692.9105913755507, 3708.9629103708617, 3717.553893532793, 673.4275865514273, 688.0951548713426, 688.8277930517371, 698.8637215742983, 705.4567982653328, 495.7347818916862, 627.6736097229415, 643.6813777180232, 689.7689274093235, 699.174973531238, 8197.385196110316, 8787.90151231096, 8987.68925712064, 9473.768410106642, 9496.762580935394, 598.2611474828583, 649.5453182771984, 663.2074670768385, 668.9732410703615, 674.639068098565, 3585.6831782826075, 4586.587207151372, 4593.173228818845, 4624.30635404692, 4642.3500709900645, 134576.0, 136616.0, 139754.0, 139971.0, 141539.29230353783, 120674.3966280163, 124431.0, 124852.0, 128253.0, 128727.0, 98239.50880245696, 107834.0, 113691.0, 116820.0, 120599.79400205886, 9504.0020797818, 9506.95246523763, 9691.370060946016, 9742.123208911233, 9837.950810245902, 60249.0, 62572.37427625847, 63132.05301920383, 64386.03420948783, 66250.33749662635, 63525.68237158785, 65331.346686532524, 85765.98371512954, 88083.0, 98150.0, 13056.738865222833, 13111.14777539457, 13187.868121319048, 13201.20792752438, 13808.792868106786, 11920.225185362924, 14705.0, 15024.774628380364, 15274.923625381802, 15582.532524024298, 13548.661283467993, 13662.590122296348, 13830.170189788645, 14069.944754143686, 14313.928430357782, 10782.460876955964, 13731.519396821062, 13760.31198440054, 13789.724205515633, 14252.786208206679, 13187.934060329637, 13292.933535332262, 13818.309084545934, 13887.097338672955, 13908.865775136206, 11177.776444471228, 11217.71955701114, 11281.548738050386, 11586.188966772244, 11656.251562265728, 8527.957360213159, 9229.215516681157, 10104.848427273744, 10327.845082323622, 10347.84478232812], "value": 7744.723717399541, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1594.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 119.0, 118.0, 116.0, 118.0, 116.0, 116.0, 116.0, 116.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 147.0, 96.0, 179.0, 100.0, 132.0, 144.0, 146.0, 120.0, 146.0], "value": 136.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 270.0, 283.0, 258.0, 282.0, 194.0, 195.0, 200.0, 195.0, 194.0], "value": 227.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 58.0, 59.0, 81.0, 88.0, 51.0, 60.0, 79.0, 104.0, 84.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 90.0, 72.0, 74.0, 89.0, 122.0, 58.0, 93.0, 73.0], "value": 82.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 135.0, 88.0, 86.0, 109.0, 87.0, 88.0, 85.0, 88.0, 86.0], "value": 98.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 152.0, 95.0, 98.0, 136.0, 166.0, 96.0, 95.0, 125.0, 128.0], "value": 123.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 91.0, 99.0, 132.0, 147.0, 89.0, 92.0, 92.0, 91.0], "value": 101.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 57.0, 58.0, 61.0, 59.0, 59.0, 57.0, 60.0, 69.0, 83.0], "value": 62.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 68.0, 88.0, 78.0, 78.0, 105.0, 105.0, 80.0, 70.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 89.0, 89.0, 91.0, 86.0, 78.0, 104.0, 107.0, 111.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 133.0, 162.0, 151.0, 139.0, 141.0, 138.0, 146.0, 187.0, 166.0], "value": 148.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 175.0, 177.0, 172.0, 170.0, 177.0, 170.0, 176.0, 175.0, 169.0], "value": 173.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 68.0, 70.0, 74.0, 69.0, 68.0, 82.0, 79.0, 91.0], "value": 74.2, "unit": "score"}]}]} 05:46:03 INFO - Return code: 0 05:46:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:46:03 ERROR - # TBPL SUCCESS # 05:46:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:46:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:46:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:46:03 INFO - 2016-09-23 05:46:03.732 screenresolution[1674:10334] starting screenresolution argv=screenresolution get 05:46:03 INFO - 2016-09-23 05:46:03.735 screenresolution[1674:10334] Display 0: 1600x1200x32@60 05:46:03 INFO - 2016-09-23 05:46:03.741 screenresolution[1675:10337] starting screenresolution argv=screenresolution list 05:46:03 INFO - Available Modes on Display 0 05:46:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:46:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:46:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:46:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:46:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:46:03 INFO - Intel Iris: 05:46:03 INFO - Chipset Model: Intel Iris 05:46:03 INFO - Type: GPU 05:46:03 INFO - Bus: Built-In 05:46:03 INFO - VRAM (Dynamic, Max): 1536 MB 05:46:03 INFO - Vendor: Intel (0x8086) 05:46:03 INFO - Device ID: 0x0a2e 05:46:03 INFO - Revision ID: 0x0009 05:46:03 INFO - Displays: 05:46:03 INFO - Display: 05:46:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:46:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:46:03 INFO - Display Serial Number: 1600x1200 60 05:46:03 INFO - Main Display: Yes 05:46:03 INFO - Mirror: Off 05:46:03 INFO - Online: Yes 05:46:03 INFO - Rotation: Supported 05:46:03 INFO - Return code: 0 05:46:03 INFO - Running post-action listener: _resource_record_post_action 05:46:03 INFO - [mozharness: 2016-09-23 12:46:03.979042Z] Finished run-tests step (success) 05:46:03 INFO - Running post-run listener: _resource_record_post_run 05:46:04 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 39221760; Write bytes: 709120000; Read time: 401; Write time: 1977 05:46:04 INFO - TinderboxPrint: CPU usage
54.5% 05:46:04 INFO - TinderboxPrint: I/O read bytes / time
39,221,760 / 401 05:46:04 INFO - TinderboxPrint: I/O write bytes / time
709,120,000 / 1,977 05:46:04 INFO - TinderboxPrint: CPU idle
2,203.4 (45.5%) 05:46:04 INFO - TinderboxPrint: CPU system
977.9 (20.2%) 05:46:04 INFO - TinderboxPrint: CPU user
1,656.4 (34.2%) 05:46:04 INFO - TinderboxPrint: Swap in / out
445,960,192 / 0 05:46:04 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 404591104; Write bytes: 393164288; Read time: 29625; Write time: 724 05:46:04 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 31830528; Write bytes: 299043328; Read time: 314; Write time: 1212 05:46:04 INFO - Running post-run listener: _upload_blobber_files 05:46:04 INFO - Blob upload gear active. 05:46:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:46:04 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 05:46:04 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'] 05:46:04 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 05:46:04 INFO - (blobuploader) - INFO - Open directory for files ... 05:46:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:46:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:46:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:46:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:46:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:46:05 INFO - (blobuploader) - INFO - Done attempting. 05:46:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:46:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:46:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:46:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:46:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:46:06 INFO - (blobuploader) - INFO - Done attempting. 05:46:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:46:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:46:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:46:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:46:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:46:09 INFO - (blobuploader) - INFO - Done attempting. 05:46:09 INFO - (blobuploader) - INFO - Iteration through files over. 05:46:09 INFO - Return code: 0 05:46:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:46:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:46:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e5b9ff326e4eeea8fdbcb27128b450f6dccbd86a2a5ffbc20f9ed99ce9326334fe39f815d86cbfec0648f67a53fcb45a5c0747695d56c95dfedf33550efde59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cad78e857b312dced0d2aae8f1f99d5e06853a11180ed1549337a70674dfcffe3f362a3f7562ee7798acb581034e7ec28fea009ab64baaf7fdc5ee3b71d8a10c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea0e1fe451d5ec8636e5dd0384c05a86ad1fd774334469a25915e74352693d74bce98733f29c215917f295796b78781a48d1ef08f3e6b93008f4e0e971ed1a60"} 05:46:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:46:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:46:09 INFO - Contents: 05:46:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e5b9ff326e4eeea8fdbcb27128b450f6dccbd86a2a5ffbc20f9ed99ce9326334fe39f815d86cbfec0648f67a53fcb45a5c0747695d56c95dfedf33550efde59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cad78e857b312dced0d2aae8f1f99d5e06853a11180ed1549337a70674dfcffe3f362a3f7562ee7798acb581034e7ec28fea009ab64baaf7fdc5ee3b71d8a10c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea0e1fe451d5ec8636e5dd0384c05a86ad1fd774334469a25915e74352693d74bce98733f29c215917f295796b78781a48d1ef08f3e6b93008f4e0e971ed1a60"} 05:46:09 INFO - Running post-run listener: copy_logs_to_upload_dir 05:46:09 INFO - Copying logs to upload dir... 05:46:09 INFO - mkdir: /builds/slave/test/build/upload/logs 05:46:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.304368 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-23 05:46:10.022784) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-23 05:46:10.026180) ========= 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.jY0SPY2xlp/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.ZQ71r3EIeA/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e5b9ff326e4eeea8fdbcb27128b450f6dccbd86a2a5ffbc20f9ed99ce9326334fe39f815d86cbfec0648f67a53fcb45a5c0747695d56c95dfedf33550efde59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cad78e857b312dced0d2aae8f1f99d5e06853a11180ed1549337a70674dfcffe3f362a3f7562ee7798acb581034e7ec28fea009ab64baaf7fdc5ee3b71d8a10c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea0e1fe451d5ec8636e5dd0384c05a86ad1fd774334469a25915e74352693d74bce98733f29c215917f295796b78781a48d1ef08f3e6b93008f4e0e971ed1a60"} build_url:https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011368 build_url: 'https://queue.taskcluster.net/v1/task/Kpu0zDUITICeOJxTFNdgtg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e5b9ff326e4eeea8fdbcb27128b450f6dccbd86a2a5ffbc20f9ed99ce9326334fe39f815d86cbfec0648f67a53fcb45a5c0747695d56c95dfedf33550efde59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cad78e857b312dced0d2aae8f1f99d5e06853a11180ed1549337a70674dfcffe3f362a3f7562ee7798acb581034e7ec28fea009ab64baaf7fdc5ee3b71d8a10c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea0e1fe451d5ec8636e5dd0384c05a86ad1fd774334469a25915e74352693d74bce98733f29c215917f295796b78781a48d1ef08f3e6b93008f4e0e971ed1a60"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-23 05:46:10.062213) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 05:46:10.062512) ========= 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.jY0SPY2xlp/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.ZQ71r3EIeA/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.005332 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 05:46:10.110551) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-23 05:46:10.110875) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-23 05:46:10.111231) ========= ========= Total master_lag: 0.18 =========