builder: date_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0295 starttime: 1474915832.51 results: success (0) buildid: 20160926094650 builduid: 301d4a31454d4e878d33469099f07efe revision: 40ee5ff8fad82e4581e49361beb1d00179521420 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-26 11:50:32.508031) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-26 11:50:32.508461) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-26 11:50:32.508829) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-26 11:50:32.537240) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 11:50:32.537626) ========= 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.2cMVMgW5ub/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.2UvYiZj37l/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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-26 11:50:32-- 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% 10.5M=0.001s 2016-09-26 11:50:32 (10.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.345363 ========= master_lag: 0.21 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 11:50:33.091155) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 11:50:33.091471) ========= 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.2cMVMgW5ub/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.2UvYiZj37l/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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.021544 ========= master_lag: 0.37 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 11:50:33.483230) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-26 11:50:33.483561) ========= bash -c 'python archiver_client.py mozharness --repo projects/date --rev 40ee5ff8fad82e4581e49361beb1d00179521420 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/date --rev 40ee5ff8fad82e4581e49361beb1d00179521420 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2cMVMgW5ub/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.2UvYiZj37l/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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-26 11:50:33,566 truncating revision to first 12 chars 2016-09-26 11:50:33,566 Setting DEBUG logging. 2016-09-26 11:50:33,566 attempt 1/10 2016-09-26 11:50:33,566 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/date/40ee5ff8fad8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-26 11:50:34,605 unpacking tar archive at: date-40ee5ff8fad8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.293502 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-26 11:50:34.890148) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-26 11:50:34.890462) ========= script_repo_revision: 40ee5ff8fad82e4581e49361beb1d00179521420 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-26 11:50:34.893910) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-26 11:50:34.894190) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-26 11:50:34.913246) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-09-26 11:50:34.913636) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Date --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Date 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', 'Date', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Date'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2cMVMgW5ub/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.2UvYiZj37l/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 11:50:35 INFO - MultiFileLogger online at 20160926 11:50:35 in /builds/slave/test 11:50:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Date --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Date 11:50:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:50:35 INFO - {'append_to_log': False, 11:50:35 INFO - 'base_work_dir': '/builds/slave/test', 11:50:35 INFO - 'blob_upload_branch': 'Date', 11:50:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:50:35 INFO - 'branch': 'Date', 11:50:35 INFO - 'buildbot_json_path': 'buildprops.json', 11:50:35 INFO - 'config_files': ('talos/mac_config.py',), 11:50:35 INFO - 'default_actions': ('clobber', 11:50:35 INFO - 'read-buildbot-config', 11:50:35 INFO - 'download-and-extract', 11:50:35 INFO - 'populate-webroot', 11:50:35 INFO - 'create-virtualenv', 11:50:35 INFO - 'install', 11:50:35 INFO - 'run-tests'), 11:50:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:50:35 INFO - 'download_minidump_stackwalk': True, 11:50:35 INFO - 'download_symbols': 'ondemand', 11:50:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:50:35 INFO - 'tooltool.py': '/tools/tooltool.py', 11:50:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:50:35 INFO - '/tools/misc-python/virtualenv.py')}, 11:50:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:50:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:50:35 INFO - 'installer_path': 'installer.exe', 11:50:35 INFO - 'log_level': 'info', 11:50:35 INFO - 'log_name': 'talos', 11:50:35 INFO - 'log_to_console': True, 11:50:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:50:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:50:35 INFO - 'opt_config_files': (), 11:50:35 INFO - 'pip_index': False, 11:50:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:50:35 INFO - 'cmd': ('bash', 11:50:35 INFO - '-c', 11:50:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:50:35 INFO - 'enabled': True, 11:50:35 INFO - 'halt_on_failure': False, 11:50:35 INFO - 'name': 'check_screen_resolution'},), 11:50:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:50:35 INFO - 'cmd': ('bash', 11:50:35 INFO - '-c', 11:50:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:50:35 INFO - 'enabled': True, 11:50:35 INFO - 'halt_on_failure': False, 11:50:35 INFO - 'name': 'check_screen_resolution'},), 11:50:35 INFO - 'run_cmd_checks_enabled': True, 11:50:35 INFO - 'sps_profile': False, 11:50:35 INFO - 'sps_profile_interval': 0, 11:50:35 INFO - 'suite': 'dromaeojs-e10s', 11:50:35 INFO - 'system_bits': '32', 11:50:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:50:35 INFO - 'title': 't-yosemite-r7-0295', 11:50:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:50:35 INFO - 'use_talos_json': True, 11:50:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:50:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:50:35 INFO - 'work_dir': 'build'} 11:50:35 INFO - [mozharness: 2016-09-26 18:50:35.113931Z] Running clobber step. 11:50:35 INFO - Running pre-action listener: _resource_record_pre_action 11:50:35 INFO - Running main action method: clobber 11:50:35 INFO - rmtree: /builds/slave/test/build 11:50:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:50:36 INFO - Running post-action listener: _resource_record_post_action 11:50:36 INFO - [mozharness: 2016-09-26 18:50:36.777541Z] Finished clobber step (success) 11:50:36 INFO - [mozharness: 2016-09-26 18:50:36.777922Z] Running read-buildbot-config step. 11:50:36 INFO - Running pre-action listener: _resource_record_pre_action 11:50:36 INFO - Running main action method: read_buildbot_config 11:50:36 INFO - Using buildbot properties: 11:50:36 INFO - { 11:50:36 INFO - "project": "", 11:50:36 INFO - "product": "firefox", 11:50:36 INFO - "script_repo_revision": "production", 11:50:36 INFO - "builddir": "date_yosemite_r7_test-dromaeojs-e10s", 11:50:36 INFO - "repository": "", 11:50:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 date talos dromaeojs-e10s", 11:50:36 INFO - "buildid": "20160926094650", 11:50:36 INFO - "pgo_build": "False", 11:50:36 INFO - "basedir": "/builds/slave/test", 11:50:36 INFO - "buildnumber": 0, 11:50:36 INFO - "slavename": "t-yosemite-r7-0295", 11:50:36 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 11:50:36 INFO - "platform": "yosemite_r7", 11:50:36 INFO - "scheduler": "tests-date-macosx64-talos", 11:50:36 INFO - "branch": "date", 11:50:36 INFO - "repo_path": "projects/date", 11:50:36 INFO - "revision": "40ee5ff8fad82e4581e49361beb1d00179521420", 11:50:36 INFO - "stage_platform": "macosx64", 11:50:36 INFO - "builduid": "301d4a31454d4e878d33469099f07efe", 11:50:36 INFO - "slavebuilddir": "test" 11:50:36 INFO - } 11:50:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 11:50:36 INFO - Running post-action listener: _resource_record_post_action 11:50:36 INFO - [mozharness: 2016-09-26 18:50:36.783754Z] Finished read-buildbot-config step (success) 11:50:36 INFO - [mozharness: 2016-09-26 18:50:36.783965Z] Running download-and-extract step. 11:50:36 INFO - Running pre-action listener: _resource_record_pre_action 11:50:36 INFO - Running main action method: download_and_extract 11:50:36 INFO - mkdir: /builds/slave/test/build/tests 11:50:36 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')]} 11:50:36 INFO - trying https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 11:50:36 INFO - Downloading https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/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 11:50:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/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 11:50:37 INFO - Downloaded 1199 bytes. 11:50:37 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 11:50:37 INFO - Using the following test package requirements: 11:50:37 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 11:50:37 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:50:37 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 11:50:37 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:50:37 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 11:50:37 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:50:37 INFO - u'jsshell-mac.zip'], 11:50:37 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:50:37 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 11:50:37 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 11:50:37 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:50:37 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 11:50:37 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:50:37 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 11:50:37 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:50:37 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 11:50:37 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:50:37 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 11:50:37 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:50:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 11:50:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:50:37 INFO - Fetch https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 11:50:38 INFO - Expected file size: 16275595 11:50:45 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 11:50:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 11:50:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:50:45 INFO - Fetch https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 11:50:46 INFO - Expected file size: 16275595 11:50:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 11:50:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:50:52 INFO - Fetch https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 11:50:53 INFO - Expected file size: 14414057 11:50:54 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')]} 11:50:54 INFO - trying https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 11:50:54 INFO - Downloading https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 11:50:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/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 11:50:56 INFO - Downloaded 83297686 bytes. 11:50:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 11:50:56 INFO - mkdir: /builds/slave/test/properties 11:50:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:50:56 INFO - Writing to file /builds/slave/test/properties/build_url 11:50:56 INFO - Contents: 11:50:56 INFO - build_url:https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 11:50:57 INFO - Running post-action listener: _resource_record_post_action 11:50:57 INFO - Running post-action listener: set_extra_try_arguments 11:50:57 INFO - [mozharness: 2016-09-26 18:50:57.980318Z] Finished download-and-extract step (success) 11:50:57 INFO - [mozharness: 2016-09-26 18:50:57.980424Z] Running populate-webroot step. 11:50:57 INFO - Running pre-action listener: _resource_record_pre_action 11:50:57 INFO - Running main action method: populate_webroot 11:50:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:50:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:50:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:50:57 INFO - '--tppagecycles', 11:50:57 INFO - '7'], 11:50:57 INFO - 'tests': ['tsvgm']}}, 11:50:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:50:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:50:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:50:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:50:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:50:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:50:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:57 INFO - 'talos_options': ['--disable-e10s'], 11:50:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:50:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:50:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:57 INFO - 'talos_options': ['--disable-e10s'], 11:50:57 INFO - 'tests': ['damp', 'tps']}, 11:50:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:57 INFO - 'tests': ['damp', 'tps']}, 11:50:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:50:57 INFO - 'tests': ['dromaeo_dom']}, 11:50:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:50:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:50:57 INFO - 'tests': ['basic_compositor_video']}, 11:50:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:50:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:50:57 INFO - 'tests': ['a11yr', 11:50:57 INFO - 'ts_paint', 11:50:57 INFO - 'tpaint', 11:50:57 INFO - 'sessionrestore', 11:50:57 INFO - 'sessionrestore_no_auto_restore', 11:50:57 INFO - 'tabpaint']}, 11:50:57 INFO - 'other-e10s': {'tests': ['a11yr', 11:50:57 INFO - 'ts_paint', 11:50:57 INFO - 'tpaint', 11:50:57 INFO - 'sessionrestore', 11:50:57 INFO - 'sessionrestore_no_auto_restore', 11:50:57 INFO - 'tabpaint']}, 11:50:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:50:57 INFO - 'ts_paint', 11:50:57 INFO - 'tpaint', 11:50:57 INFO - 'sessionrestore', 11:50:57 INFO - 'sessionrestore_no_auto_restore', 11:50:57 INFO - 'tabpaint']}, 11:50:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:50:57 INFO - 'ts_paint', 11:50:57 INFO - 'tpaint', 11:50:57 INFO - 'sessionrestore', 11:50:57 INFO - 'sessionrestore_no_auto_restore', 11:50:57 INFO - 'tabpaint']}, 11:50:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:50:57 INFO - 'tests': ['a11yr', 11:50:57 INFO - 'ts_paint', 11:50:57 INFO - 'tpaint', 11:50:57 INFO - 'sessionrestore', 11:50:57 INFO - 'sessionrestore_no_auto_restore', 11:50:57 INFO - 'tabpaint']}, 11:50:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:50:57 INFO - 'tests': ['a11yr', 11:50:57 INFO - 'ts_paint', 11:50:57 INFO - 'tpaint', 11:50:57 INFO - 'sessionrestore', 11:50:57 INFO - 'sessionrestore_no_auto_restore', 11:50:57 INFO - 'tabpaint']}, 11:50:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:50:57 INFO - 'tests': ['tsvgx', 11:50:57 INFO - 'tsvgr_opacity', 11:50:57 INFO - 'tart', 11:50:57 INFO - 'tscrollx', 11:50:57 INFO - 'cart']}, 11:50:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:50:57 INFO - 'tsvgr_opacity', 11:50:57 INFO - 'tart', 11:50:57 INFO - 'tscrollx', 11:50:57 INFO - 'cart']}, 11:50:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:57 INFO - 'talos_options': ['--disable-e10s'], 11:50:57 INFO - 'tests': ['tp5o']}, 11:50:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:57 INFO - 'tests': ['tp5o']}, 11:50:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:57 INFO - 'talos_options': ['--disable-e10s', 11:50:57 INFO - '--xperf_path', 11:50:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:50:57 INFO - 'tests': ['tp5n']}, 11:50:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:57 INFO - 'talos_options': ['--xperf_path', 11:50:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:50:57 INFO - 'tests': ['tp5n']}}, 11:50:57 INFO - 'talos.zip': {'path': '', 11:50:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:50:57 INFO - Running post-action listener: _resource_record_post_action 11:50:57 INFO - [mozharness: 2016-09-26 18:50:57.999857Z] Finished populate-webroot step (success) 11:50:58 INFO - [mozharness: 2016-09-26 18:50:57.999986Z] Running create-virtualenv step. 11:50:58 INFO - Running pre-action listener: _resource_record_pre_action 11:50:58 INFO - Running main action method: create_virtualenv 11:50:58 INFO - Creating virtualenv /builds/slave/test/build/venv 11:50:58 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 11:50:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:50:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:50:58 INFO - Using real prefix '/tools/python27' 11:50:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:50:58 INFO - Installing distribute.............................................................................................................................................................................................done. 11:51:01 INFO - Installing pip.................done. 11:51:01 INFO - Return code: 0 11:51:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:51:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:51:01 INFO - Reading from file tmpfile_stdout 11:51:01 INFO - Output received: 11:51:01 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 11:51:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:51:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:51:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:51:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1089e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa88bda4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fad30>, '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 0x108620db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f2b20>, '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.2cMVMgW5ub/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.2UvYiZj37l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 11:51:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:51:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:51:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2cMVMgW5ub/Render', 11:51:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:01 INFO - 'HOME': '/Users/cltbld', 11:51:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:01 INFO - 'LOGNAME': 'cltbld', 11:51:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:01 INFO - 'MOZ_NO_REMOTE': '1', 11:51:01 INFO - 'NO_EM_RESTART': '1', 11:51:01 INFO - 'PAGER': '/bin/cat', 11:51:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:01 INFO - 'PWD': '/builds/slave/test', 11:51:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:01 INFO - 'SHELL': '/bin/bash', 11:51:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2UvYiZj37l/Listeners', 11:51:01 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 11:51:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:01 INFO - 'USER': 'cltbld', 11:51:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:01 INFO - 'XPC_FLAGS': '0x0', 11:51:01 INFO - 'XPC_SERVICE_NAME': '0', 11:51:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:02 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 11:51:02 INFO - Cleaning up... 11:51:02 INFO - Return code: 0 11:51:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:51:02 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')]} 11:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:02 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')]} 11:51:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:02 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 0x1089e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa88bda4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fad30>, '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 0x108620db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f2b20>, '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.2cMVMgW5ub/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.2UvYiZj37l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 11:51:02 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 11:51:02 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 11:51:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2cMVMgW5ub/Render', 11:51:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:02 INFO - 'HOME': '/Users/cltbld', 11:51:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:02 INFO - 'LOGNAME': 'cltbld', 11:51:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:02 INFO - 'MOZ_NO_REMOTE': '1', 11:51:02 INFO - 'NO_EM_RESTART': '1', 11:51:02 INFO - 'PAGER': '/bin/cat', 11:51:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:02 INFO - 'PWD': '/builds/slave/test', 11:51:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:02 INFO - 'SHELL': '/bin/bash', 11:51:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2UvYiZj37l/Listeners', 11:51:02 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 11:51:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:02 INFO - 'USER': 'cltbld', 11:51:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:02 INFO - 'XPC_FLAGS': '0x0', 11:51:02 INFO - 'XPC_SERVICE_NAME': '0', 11:51:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:02 INFO - Downloading/unpacking psutil>=3.1.1 11:51:02 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 11:51:02 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 11:51:02 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 11:51:02 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 11:51:02 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 11:51:02 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 11:51:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:51:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:51:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:51:05 INFO - Installing collected packages: psutil 11:51:05 INFO - Running setup.py install for psutil 11:51:05 INFO - building 'psutil._psutil_osx' extension 11:51:05 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 11:51:05 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 11:51:05 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 11:51:05 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 11:51:05 INFO - building 'psutil._psutil_posix' extension 11:51:05 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 11:51:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:51:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:51:06 INFO - ^ 11:51:06 INFO - 1 warning generated. 11:51:06 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 11:51:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:51:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:51:06 INFO - Successfully installed psutil 11:51:06 INFO - Cleaning up... 11:51:06 INFO - Return code: 0 11:51:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:51:06 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')]} 11:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:06 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')]} 11:51:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:06 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 0x1089e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa88bda4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fad30>, '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 0x108620db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f2b20>, '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.2cMVMgW5ub/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.2UvYiZj37l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 11:51:06 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 11:51:06 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 11:51:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2cMVMgW5ub/Render', 11:51:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:06 INFO - 'HOME': '/Users/cltbld', 11:51:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:06 INFO - 'LOGNAME': 'cltbld', 11:51:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:06 INFO - 'MOZ_NO_REMOTE': '1', 11:51:06 INFO - 'NO_EM_RESTART': '1', 11:51:06 INFO - 'PAGER': '/bin/cat', 11:51:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:06 INFO - 'PWD': '/builds/slave/test', 11:51:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:06 INFO - 'SHELL': '/bin/bash', 11:51:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2UvYiZj37l/Listeners', 11:51:06 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 11:51:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:06 INFO - 'USER': 'cltbld', 11:51:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:06 INFO - 'XPC_FLAGS': '0x0', 11:51:06 INFO - 'XPC_SERVICE_NAME': '0', 11:51:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:51:06 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 11:51:06 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 11:51:06 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 11:51:06 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 11:51:06 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 11:51:06 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 11:51:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:51:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:51:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:51:09 INFO - Installing collected packages: mozsystemmonitor 11:51:09 INFO - Running setup.py install for mozsystemmonitor 11:51:09 INFO - Successfully installed mozsystemmonitor 11:51:09 INFO - Cleaning up... 11:51:09 INFO - Return code: 0 11:51:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:51:09 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')]} 11:51:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:09 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')]} 11:51:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:09 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 0x1089e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa88bda4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fad30>, '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 0x108620db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f2b20>, '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.2cMVMgW5ub/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.2UvYiZj37l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 11:51:09 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 11:51:09 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 11:51:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2cMVMgW5ub/Render', 11:51:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:09 INFO - 'HOME': '/Users/cltbld', 11:51:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:09 INFO - 'LOGNAME': 'cltbld', 11:51:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:09 INFO - 'MOZ_NO_REMOTE': '1', 11:51:09 INFO - 'NO_EM_RESTART': '1', 11:51:09 INFO - 'PAGER': '/bin/cat', 11:51:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:09 INFO - 'PWD': '/builds/slave/test', 11:51:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:09 INFO - 'SHELL': '/bin/bash', 11:51:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2UvYiZj37l/Listeners', 11:51:09 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 11:51:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:09 INFO - 'USER': 'cltbld', 11:51:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:09 INFO - 'XPC_FLAGS': '0x0', 11:51:09 INFO - 'XPC_SERVICE_NAME': '0', 11:51:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:09 INFO - Downloading/unpacking jsonschema==2.5.1 11:51:09 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 11:51:09 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 11:51:09 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 11:51:09 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 11:51:09 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 11:51:09 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 11:51:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:51:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:12 INFO - Downloading functools32-3.2.3-2.tar.gz 11:51:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:51:12 INFO - warning: no files found matching '*.txt' 11:51:12 INFO - no previously-included directories found matching 'build' 11:51:12 INFO - no previously-included directories found matching 'dist' 11:51:12 INFO - no previously-included directories found matching '.git*' 11:51:12 INFO - Installing collected packages: jsonschema, functools32 11:51:12 INFO - Running setup.py install for functools32 11:51:12 INFO - warning: no files found matching '*.txt' 11:51:12 INFO - no previously-included directories found matching 'build' 11:51:12 INFO - no previously-included directories found matching 'dist' 11:51:12 INFO - no previously-included directories found matching '.git*' 11:51:12 INFO - Successfully installed jsonschema functools32 11:51:12 INFO - Cleaning up... 11:51:12 INFO - Return code: 0 11:51:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:51:12 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')]} 11:51:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:51:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:13 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 0x1089e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa88bda4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fad30>, '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 0x108620db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f2b20>, '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.2cMVMgW5ub/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.2UvYiZj37l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 11:51:13 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 11:51:13 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 11:51:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2cMVMgW5ub/Render', 11:51:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:13 INFO - 'HOME': '/Users/cltbld', 11:51:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:13 INFO - 'LOGNAME': 'cltbld', 11:51:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:13 INFO - 'MOZ_NO_REMOTE': '1', 11:51:13 INFO - 'NO_EM_RESTART': '1', 11:51:13 INFO - 'PAGER': '/bin/cat', 11:51:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:13 INFO - 'PWD': '/builds/slave/test', 11:51:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:13 INFO - 'SHELL': '/bin/bash', 11:51:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2UvYiZj37l/Listeners', 11:51:13 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 11:51:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:13 INFO - 'USER': 'cltbld', 11:51:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:13 INFO - 'XPC_FLAGS': '0x0', 11:51:13 INFO - 'XPC_SERVICE_NAME': '0', 11:51:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:51:13 INFO - Cleaning up... 11:51:13 INFO - Return code: 0 11:51:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:51:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:13 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 0x1089e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa88bda4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fad30>, '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 0x108620db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f2b20>, '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.2cMVMgW5ub/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.2UvYiZj37l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 11:51:13 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 11:51:13 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 11:51:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2cMVMgW5ub/Render', 11:51:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:13 INFO - 'HOME': '/Users/cltbld', 11:51:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:13 INFO - 'LOGNAME': 'cltbld', 11:51:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:13 INFO - 'MOZ_NO_REMOTE': '1', 11:51:13 INFO - 'NO_EM_RESTART': '1', 11:51:13 INFO - 'PAGER': '/bin/cat', 11:51:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:13 INFO - 'PWD': '/builds/slave/test', 11:51:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:13 INFO - 'SHELL': '/bin/bash', 11:51:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2UvYiZj37l/Listeners', 11:51:13 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 11:51:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:13 INFO - 'USER': 'cltbld', 11:51:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:13 INFO - 'XPC_FLAGS': '0x0', 11:51:13 INFO - 'XPC_SERVICE_NAME': '0', 11:51:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:13 INFO - Downloading/unpacking blobuploader==1.2.4 11:51:13 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 11:51:13 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 11:51:13 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 11:51:13 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 11:51:13 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 11:51:13 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 11:51:16 INFO - Downloading blobuploader-1.2.4.tar.gz 11:51:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:51:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:51:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:17 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 11:51:17 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 11:51:17 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 11:51:17 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 11:51:17 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 11:51:17 INFO - Downloading docopt-0.6.1.tar.gz 11:51:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:51:17 INFO - Installing collected packages: blobuploader, requests, docopt 11:51:17 INFO - Running setup.py install for blobuploader 11:51:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:51:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:51:17 INFO - Running setup.py install for requests 11:51:18 INFO - Running setup.py install for docopt 11:51:18 INFO - Successfully installed blobuploader requests docopt 11:51:18 INFO - Cleaning up... 11:51:18 INFO - Return code: 0 11:51:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:51: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')]} 11:51:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51: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')]} 11:51:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:18 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 0x1089e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa88bda4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fad30>, '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 0x108620db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f2b20>, '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.2cMVMgW5ub/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.2UvYiZj37l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 11:51:18 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 11:51:18 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 11:51:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2cMVMgW5ub/Render', 11:51:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:18 INFO - 'HOME': '/Users/cltbld', 11:51:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:18 INFO - 'LOGNAME': 'cltbld', 11:51:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:18 INFO - 'MOZ_NO_REMOTE': '1', 11:51:18 INFO - 'NO_EM_RESTART': '1', 11:51:18 INFO - 'PAGER': '/bin/cat', 11:51:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:18 INFO - 'PWD': '/builds/slave/test', 11:51:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:18 INFO - 'SHELL': '/bin/bash', 11:51:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2UvYiZj37l/Listeners', 11:51:18 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 11:51:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:18 INFO - 'USER': 'cltbld', 11:51:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:18 INFO - 'XPC_FLAGS': '0x0', 11:51:18 INFO - 'XPC_SERVICE_NAME': '0', 11:51:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:51:18 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-61Exv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:51:18 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-FrAPhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:51:18 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-fYFkmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:51:19 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-_Q3kcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:51:19 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-fQgE0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:51:19 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-rRmAJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:51:19 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-EUOFZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:51:19 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-7mpJUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:51:19 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-l0RPvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:51:19 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-HOH0in-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:51:19 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-udqrm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:51:19 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-JI9V9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:51:19 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-GIxzQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:51:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:51:20 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-YgeCgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:51:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:51:20 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-0zcZUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:51:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:51:20 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-ZPj2Gh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:51:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:51:20 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-7BmE6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:51:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:51:20 INFO - Running setup.py install for manifestparser 11:51:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:51:20 INFO - Running setup.py install for mozcrash 11:51:20 INFO - Running setup.py install for mozdebug 11:51:20 INFO - Running setup.py install for mozdevice 11:51:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:51:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:51:21 INFO - Running setup.py install for mozfile 11:51:21 INFO - Running setup.py install for mozhttpd 11:51:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:51:21 INFO - Running setup.py install for mozinfo 11:51:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:51:21 INFO - Running setup.py install for mozInstall 11:51:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:51:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:51:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:51:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:51:21 INFO - Running setup.py install for mozleak 11:51:21 INFO - Running setup.py install for mozlog 11:51:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:51:22 INFO - Running setup.py install for moznetwork 11:51:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:51:22 INFO - Running setup.py install for mozprocess 11:51:22 INFO - Running setup.py install for mozprofile 11:51:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:51:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:51:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:51:22 INFO - Running setup.py install for mozrunner 11:51:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:51:22 INFO - Running setup.py install for mozscreenshot 11:51:22 INFO - Running setup.py install for moztest 11:51:22 INFO - Running setup.py install for mozversion 11:51:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:51:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:51:23 INFO - Cleaning up... 11:51:23 INFO - Return code: 0 11:51:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:51:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:51:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:51:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:23 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 0x1089e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa88bda4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fad30>, '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 0x108620db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f2b20>, '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.2cMVMgW5ub/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.2UvYiZj37l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 11:51:23 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 11:51:23 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 11:51:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2cMVMgW5ub/Render', 11:51:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:23 INFO - 'HOME': '/Users/cltbld', 11:51:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:23 INFO - 'LOGNAME': 'cltbld', 11:51:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:23 INFO - 'MOZ_NO_REMOTE': '1', 11:51:23 INFO - 'NO_EM_RESTART': '1', 11:51:23 INFO - 'PAGER': '/bin/cat', 11:51:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:23 INFO - 'PWD': '/builds/slave/test', 11:51:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:23 INFO - 'SHELL': '/bin/bash', 11:51:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2UvYiZj37l/Listeners', 11:51:23 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 11:51:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:23 INFO - 'USER': 'cltbld', 11:51:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:23 INFO - 'XPC_FLAGS': '0x0', 11:51:23 INFO - 'XPC_SERVICE_NAME': '0', 11:51:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:51:23 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-lDprUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:51:23 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)) 11:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:51:23 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-3d7iO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:51:23 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)) 11:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:51:23 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-bSaewP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:51:23 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)) 11:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:51:23 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-R7KbuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:51:23 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)) 11:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:51:23 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-mZ7ZHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:51:23 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)) 11:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:51:23 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-EKntPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:51:24 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)) 11:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:51:24 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-lG6i0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:51:24 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)) 11:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:51:24 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-oVvp1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:51:24 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)) 11:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:51:24 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-S6T768-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:51:24 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)) 11:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:51:24 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-h5afg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:51:24 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)) 11:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:51:24 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-ui6Det-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:51:24 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)) 11:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:51:24 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-0RNyvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:51:24 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)) 11:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:51:24 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-w4H5aq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:51:24 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)) 11:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:51:24 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-T3BDN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:51:24 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)) 11:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:51:24 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-Kx3Dss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:51:25 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)) 11:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:51:25 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-X7Lccq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:51:25 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)) 11:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:51:25 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-x9klBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:51:25 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)) 11:51:25 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)) 11:51:25 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)) 11:51:25 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)) 11:51:25 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)) 11:51:25 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)) 11:51:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:51:25 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 11:51:25 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 11:51:25 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 11:51:25 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 11:51:25 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 11:51:25 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 11:51:27 INFO - Downloading blessings-1.6.tar.gz 11:51:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:51:27 INFO - Installing collected packages: blessings 11:51:27 INFO - Running setup.py install for blessings 11:51:27 INFO - Successfully installed blessings 11:51:27 INFO - Cleaning up... 11:51:28 INFO - Return code: 0 11:51:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:51:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:51:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:51:28 INFO - Reading from file tmpfile_stdout 11:51:28 INFO - Current package versions: 11:51:28 INFO - blessings == 1.6 11:51:28 INFO - blobuploader == 1.2.4 11:51:28 INFO - docopt == 0.6.1 11:51:28 INFO - functools32 == 3.2.3-2 11:51:28 INFO - jsonschema == 2.5.1 11:51:28 INFO - manifestparser == 1.1 11:51:28 INFO - mozInstall == 1.12 11:51:28 INFO - mozcrash == 0.17 11:51:28 INFO - mozdebug == 0.1 11:51:28 INFO - mozdevice == 0.48 11:51:28 INFO - mozfile == 1.2 11:51:28 INFO - mozhttpd == 0.7 11:51:28 INFO - mozinfo == 0.9 11:51:28 INFO - mozleak == 0.1 11:51:28 INFO - mozlog == 3.4 11:51:28 INFO - moznetwork == 0.27 11:51:28 INFO - mozprocess == 0.23 11:51:28 INFO - mozprofile == 0.28 11:51:28 INFO - mozrunner == 6.12 11:51:28 INFO - mozscreenshot == 0.1 11:51:28 INFO - mozsystemmonitor == 0.3 11:51:28 INFO - moztest == 0.7 11:51:28 INFO - mozversion == 1.4 11:51:28 INFO - psutil == 3.1.1 11:51:28 INFO - requests == 1.2.3 11:51:28 INFO - wsgiref == 0.1.2 11:51:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:51: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')]} 11:51:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51: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')]} 11:51:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:28 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 0x1089e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa88bda4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fad30>, '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 0x108620db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f2b20>, '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.2cMVMgW5ub/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.2UvYiZj37l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 11:51:28 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 11:51:28 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 11:51:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2cMVMgW5ub/Render', 11:51:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:28 INFO - 'HOME': '/Users/cltbld', 11:51:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:28 INFO - 'LOGNAME': 'cltbld', 11:51:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:28 INFO - 'MOZ_NO_REMOTE': '1', 11:51:28 INFO - 'NO_EM_RESTART': '1', 11:51:28 INFO - 'PAGER': '/bin/cat', 11:51:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:28 INFO - 'PWD': '/builds/slave/test', 11:51:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:28 INFO - 'SHELL': '/bin/bash', 11:51:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2UvYiZj37l/Listeners', 11:51:28 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 11:51:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:28 INFO - 'USER': 'cltbld', 11:51:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:28 INFO - 'XPC_FLAGS': '0x0', 11:51:28 INFO - 'XPC_SERVICE_NAME': '0', 11:51:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:28 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)) 11:51:28 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)) 11:51:28 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)) 11:51:28 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)) 11:51:28 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)) 11:51:28 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)) 11:51:28 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)) 11:51:28 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)) 11:51:28 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)) 11:51:28 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)) 11:51:28 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)) 11:51:28 INFO - Cleaning up... 11:51:28 INFO - Return code: 0 11:51:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:51: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')]} 11:51:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51: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')]} 11:51:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:28 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 0x1089e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa88bda4800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fad30>, '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 0x108620db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f2b20>, '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.2cMVMgW5ub/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.2UvYiZj37l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 11:51:28 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 11:51:28 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 11:51:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2cMVMgW5ub/Render', 11:51:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:28 INFO - 'HOME': '/Users/cltbld', 11:51:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:28 INFO - 'LOGNAME': 'cltbld', 11:51:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:28 INFO - 'MOZ_NO_REMOTE': '1', 11:51:28 INFO - 'NO_EM_RESTART': '1', 11:51:28 INFO - 'PAGER': '/bin/cat', 11:51:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:28 INFO - 'PWD': '/builds/slave/test', 11:51:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:28 INFO - 'SHELL': '/bin/bash', 11:51:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2UvYiZj37l/Listeners', 11:51:28 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 11:51:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:28 INFO - 'USER': 'cltbld', 11:51:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:28 INFO - 'XPC_FLAGS': '0x0', 11:51:28 INFO - 'XPC_SERVICE_NAME': '0', 11:51:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:51:28 INFO - Cleaning up... 11:51:28 INFO - Return code: 0 11:51:28 INFO - Running post-action listener: _resource_record_post_action 11:51:28 INFO - Running post-action listener: _start_resource_monitoring 11:51:28 INFO - Starting resource monitoring. 11:51:28 INFO - [mozharness: 2016-09-26 18:51:28.898018Z] Finished create-virtualenv step (success) 11:51:28 INFO - [mozharness: 2016-09-26 18:51:28.899093Z] Running install step. 11:51:28 INFO - Running pre-action listener: _resource_record_pre_action 11:51:28 INFO - Running main action method: install 11:51:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:51:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:51:29 INFO - Reading from file tmpfile_stdout 11:51:29 INFO - Detecting whether we're running mozinstall >=1.0... 11:51:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:51:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:51:29 INFO - Reading from file tmpfile_stdout 11:51:29 INFO - Output received: 11:51:29 INFO - Usage: mozinstall [options] installer 11:51:29 INFO - Options: 11:51:29 INFO - -h, --help show this help message and exit 11:51:29 INFO - -d DEST, --destination=DEST 11:51:29 INFO - Directory to install application into. [default: 11:51:29 INFO - "/builds/slave/test"] 11:51:29 INFO - --app=APP Application being installed. [default: firefox] 11:51:29 INFO - mkdir: /builds/slave/test/build/application 11:51:29 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'] 11:51:29 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 11:51:53 INFO - Reading from file tmpfile_stdout 11:51:53 INFO - Output received: 11:51:53 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 11:51:53 INFO - Running post-action listener: _resource_record_post_action 11:51:53 INFO - [mozharness: 2016-09-26 18:51:53.491026Z] Finished install step (success) 11:51:53 INFO - [mozharness: 2016-09-26 18:51:53.491154Z] Running run-tests step. 11:51:53 INFO - Running pre-action listener: _resource_record_pre_action 11:51:53 INFO - Running main action method: run_tests 11:51:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:51:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:51:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:51:53 INFO - 2016-09-26 11:51:53.506 screenresolution[3076:22359] starting screenresolution argv=screenresolution get 11:51:53 INFO - 2016-09-26 11:51:53.508 screenresolution[3076:22359] Display 0: 1600x1200x32@60 11:51:53 INFO - 2016-09-26 11:51:53.514 screenresolution[3077:22362] starting screenresolution argv=screenresolution list 11:51:53 INFO - Available Modes on Display 0 11:51:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:51:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:51:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:51:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:51:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:51:53 INFO - Intel Iris: 11:51:53 INFO - Chipset Model: Intel Iris 11:51:53 INFO - Type: GPU 11:51:53 INFO - Bus: Built-In 11:51:53 INFO - VRAM (Dynamic, Max): 1536 MB 11:51:53 INFO - Vendor: Intel (0x8086) 11:51:53 INFO - Device ID: 0x0a2e 11:51:53 INFO - Revision ID: 0x0009 11:51:53 INFO - Displays: 11:51:53 INFO - Display: 11:51:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:51:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:51:53 INFO - Display Serial Number: 1600x1200 60 11:51:53 INFO - Main Display: Yes 11:51:53 INFO - Mirror: Off 11:51:53 INFO - Online: Yes 11:51:53 INFO - Rotation: Supported 11:51:53 INFO - Return code: 0 11:51:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:51:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:51:53 INFO - Python 2.7.3 11:51:53 INFO - Return code: 0 11:51:53 INFO - grabbing minidump binary from tooltool 11:51: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')]} 11:51:53 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 0x1087fad30>, '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 0x108620db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087f2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:51:53 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 11:51:53 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 11:51:53 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 11:51:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:51:53 INFO - Return code: 0 11:51:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:51:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:51:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:51:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:51:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:51:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Date', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0295', '--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 11:51:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Date --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0295 --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 11:51:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2cMVMgW5ub/Render', 11:51:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:53 INFO - 'HOME': '/Users/cltbld', 11:51:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:53 INFO - 'LOGNAME': 'cltbld', 11:51:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:51:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:51:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:53 INFO - 'MOZ_NO_REMOTE': '1', 11:51:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:51:53 INFO - 'NO_EM_RESTART': '1', 11:51:53 INFO - 'PAGER': '/bin/cat', 11:51:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:53 INFO - 'PWD': '/builds/slave/test', 11:51:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:51:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:53 INFO - 'SHELL': '/bin/bash', 11:51:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2UvYiZj37l/Listeners', 11:51:53 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 11:51:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:53 INFO - 'USER': 'cltbld', 11:51:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:53 INFO - 'XPC_FLAGS': '0x0', 11:51:53 INFO - 'XPC_SERVICE_NAME': '0', 11:51:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Date', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0295', '--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 11:51:54 INFO - mozversion application_buildid: 20160926094650 11:51:54 INFO - mozversion application_changeset: 40ee5ff8fad82e4581e49361beb1d00179521420 11:51:54 INFO - mozversion application_display_name: Firefox 11:51:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:51:54 INFO - mozversion application_name: Firefox 11:51:54 INFO - mozversion application_remotingname: firefox 11:51:54 INFO - mozversion application_repository: https://hg.mozilla.org/projects/date 11:51:54 INFO - mozversion application_vendor: Mozilla 11:51:54 INFO - mozversion application_version: 52.0a1 11:51:54 INFO - mozversion platform_buildid: 20160926094650 11:51:54 INFO - mozversion platform_changeset: 40ee5ff8fad82e4581e49361beb1d00179521420 11:51:54 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/date 11:51:54 INFO - mozversion platform_version: 52.0a1 11:51:54 INFO - using testdate: 1474915914 11:51:54 INFO - actual date: 1474915914 11:51:54 INFO - starting webserver on 'localhost:52760' 11:51:54 INFO - SUITE-START | Running 2 tests 11:51:54 INFO - TEST-START | dromaeo_css 11:51:54 INFO - operating with platform_type : mac_ 11:51:54 INFO - Initialising browser for dromaeo_css test... 11:51:54 INFO - TEST-INFO | started process 3087 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpYRpdXs/profile http://localhost:52760/getInfo.html) 11:51:56 INFO - PROCESS | 3087 | 2016-09-26 11:51:56.735 plugin-container[3089:22525] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4b, name = 'com.apple.tsm.portname' 11:51:56 INFO - PROCESS | 3087 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:51:56 INFO - PROCESS | 3087 | 2016-09-26 11:51:56.738 plugin-container[3089:22525] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.CFPasteboardClient' 11:51:56 INFO - PROCESS | 3087 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:51:56 INFO - PROCESS | 3087 | 2016-09-26 11:51:56.738 plugin-container[3089:22525] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:51:56 INFO - PROCESS | 3087 | __metrics Screen width/height:1600/1200 11:51:56 INFO - PROCESS | 3087 | colorDepth:24 11:51:56 INFO - PROCESS | 3087 | Browser inner width/height: 1024/689 11:51:56 INFO - PROCESS | 3087 | __metrics 11:51:56 ERROR - PROCESS | 3087 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 11:51:57 INFO - TEST-INFO | 3087: exit 0 11:51:57 INFO - Browser initialized. 11:51:57 INFO - Running cycle 1/1 for dromaeo_css test... 11:51:57 INFO - TEST-INFO | started process 3090 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpYRpdXs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:52:00 INFO - PROCESS | 3090 | 2016-09-26 11:52:00.039 plugin-container[3091:22634] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.tsm.portname' 11:52:00 INFO - PROCESS | 3090 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:52:00 INFO - PROCESS | 3090 | 2016-09-26 11:52:00.041 plugin-container[3091:22634] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c13, name = 'com.apple.CFPasteboardClient' 11:52:00 INFO - PROCESS | 3090 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:52:00 INFO - PROCESS | 3090 | 2016-09-26 11:52:00.041 plugin-container[3091:22634] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:52:09 INFO - PROCESS | 3090 | RSS: Main: 210788352 11:52:09 INFO - PROCESS | 3090 | 11:52:59 INFO - PROCESS | 3090 | 1474915979364 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] 11:55:11 INFO - PROCESS | 3090 | Cycle 1(1): loaded http://localhost:52760/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52760/tests/dromaeo/cssquery-ext.html) 11:55:13 INFO - PROCESS | 3090 | RSS: Main: 227307520 11:55:13 INFO - PROCESS | 3090 | 11:58:15 INFO - PROCESS | 3090 | Cycle 1(1): loaded http://localhost:52760/tests/dromaeo/cssquery-ext.html (next: http://localhost:52760/tests/dromaeo/cssquery-jquery.html) 11:58:16 INFO - PROCESS | 3090 | RSS: Main: 237735936 11:58:16 INFO - PROCESS | 3090 | 12:01:18 INFO - PROCESS | 3090 | Cycle 1(1): loaded http://localhost:52760/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52760/tests/dromaeo/cssquery-mootools.html) 12:01:18 INFO - PROCESS | 3090 | RSS: Main: 241221632 12:01:18 INFO - PROCESS | 3090 | 12:04:20 INFO - PROCESS | 3090 | Cycle 1(1): loaded http://localhost:52760/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52760/tests/dromaeo/cssquery-prototype.html) 12:04:21 INFO - PROCESS | 3090 | RSS: Main: 240652288 12:04:21 INFO - PROCESS | 3090 | 12:07:24 INFO - PROCESS | 3090 | Cycle 1(1): loaded http://localhost:52760/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52760/tests/dromaeo/cssquery-yui.html) 12:07:24 INFO - PROCESS | 3090 | RSS: Main: 245800960 12:07:24 INFO - PROCESS | 3090 | 12:10:27 INFO - PROCESS | 3090 | Cycle 1(1): loaded http://localhost:52760/tests/dromaeo/cssquery-yui.html (next: http://localhost:52760/tests/dromaeo/cssquery-dojo.html) 12:10:27 INFO - PROCESS | 3090 | RSS: Main: 248578048 12:10:27 INFO - PROCESS | 3090 | 12:10:27 INFO - PROCESS | 3090 | __start_tp_report 12:10:27 INFO - PROCESS | 3090 | _x_x_mozilla_page_load 12:10:27 INFO - PROCESS | 3090 | _x_x_mozilla_page_load_details 12:10:27 INFO - PROCESS | 3090 | |i|pagename|runs| 12:10:27 INFO - PROCESS | 3090 | |0;dojo.html;3229.0474310078525;3265.7224135948445;3307.0905500987224;3312.0726196664914;3324.0692606070293;5959.940400595992;5987.7604895804225;6009.0685943678745;6020.729067191976;6088.695565221732;6002.8799424011495;6009.06859436788;6045.0932360146;6067.908981365272;6099.146119543268;5364.517193452589;5407.351117865862;5448.182772584124;5451.291332126818;5507.953488837128;5468.7539060742165;5544.1960915667205;5565.777368905239;5587.664740115606;5590.720463976805;5385.272988146589;5409;5503.284573005523;5521;5536.390996990328;4105.958940410617;5399.676019438807;5456.945430545684;5521.2270282160525;5553.694546799924;5360.65155764874;5385.272988146628;5414;5419.430959749248;5444.265024221759;5260;5557.832855100433;5941.613795103348;5944.702764861783;6167.383261673842;4834.564889159992;5554.666879948783;5775.16260142277;6125.682978159717;6306.558540902139;6486.777069302808;6542.378474044958;6553.508486863504;6558.016297555358;6566.901496477557;6178.258608966908;6195.659238741912;6209.59637623553;6210.441060304549;6213.937860621336;6309.936900630935;6465.709043093072;6467.4179323860135;6509.544331896816;6535.281119076993;5213.7914483421;6254.21822272216;6329.303776584572;6332.841678958063;6335.873282534416;5502.862428439321;6137.438273608381;6418;6471.4822814175695;6507.739690412331;5055.848324550269;6229.345918678474;6231.376862313732;6273.12176295319;6286.874262514815;5488.792465657549;6302.8424289392215;6371.076193951943;6412.35876412355;6414.550981431348;4446.244138496463;4667.813287468532;5201.089809283436;5214.165733482625;5220.981908527877;4493.348464472699;4561.338605902126;4581.015081757439;4668.836590719308;4676.55572720591;6104.298005729309;6111.174991376196;6121.02133613155;6166.969165154145;6177.81466556004;6495.058216558573;6514.348565143448;6522.347765223534;6525.086487891699;6536.575122617015;5451.2640793493165;6087.878242435127;6118.6022908511695;6156.199694039746;6172.197614310201;5645.971770141123;5685.118806584986;5744.195812586241;5769.624974376652;5815.127730840323;6553.541252112402;6564.67176641166;6597.27429982692;6599.340065993362;6622.569532980245;6234.189555357774;6386.648734319657;6407;6407.167068281094;6421.293657697743;6519.902201466887;6540.607563546202;6549.8690026200165;6553.705083271169;6730.32696730323;6395.392437718409;6400.903986440114;6417.679116044273;6421.775237866652;6435.646039467874;5724.313082430135;5815.82552523425;6521.054447105048;6531.085648009187;6546.443552298108;6126.8230438726505;6170.074488826712;6171.1668924694595;6183.196184495987;6223.408776166257;5795.8551036224435;6257.436830685078;6262.655553944577;6281.685915704288;6292.653904035322;5996.730147143477;6123.70969670409;6173.81478555644;6181.938180618316;6212.689365531615;6211.254649442096;6211.9689401554515;6241.095041218907;6331.556791024584;6346.777862774781;5253.212018197301;6191.566590338633;6205.100260462298;6235.563510554399;6239.625622462667 12:10:27 INFO - PROCESS | 3090 | |1;ext.html;530.6046994988733;542.6120323968364;543.3642638113407;543.9075357189278;560.1513706734297;36643.633563664356;42093;42927.785361073154;43176.352354714705;43819.12361752763;33466.995990120275;40052;42923.78538107305;45624.85937851555;46043;8398.412111152222;9713.61145554177;9883.061109194616;9906;9915.90084099157;8517.190866867637;8554.35842311829;9473.28950328728;9643.855342169873;9725;9741.415515069119;9741.805163896717;9753.658621948232;9764.168391166553;9800.803983920317;9628.614855405775;9701.708948731513;9702.51487425626;9715.757106072335;9790.461524616143;9697.6605818796;9718.514074296258;9728.61085556584;9758.658446954394;9762.853557196648;40935.38596921019;41249.38125928114;41303.967400814916;41336.58663413357;41342.586574134475;3554.253606742587;3592.982035089834;3703.9814800925824;4024.537178224483;4053.43251944728;27342.726572734417;31580.36839263225;31957.84021079903;32142.357152856814;32921.6707832921;3327.88352407667;3344.6153692325206;3354.798712077283;3413.7951722896705;3471.062813040465;402582.98708506563;478713;492582;492771;494431.52784236206;34025.82987085049;56320.718396407756;56621.43378566244;56721.43278567244;56737.43262567322;35127.648723512946;35721.82139089288;35912.28175436477;36012.739554115484;36020.639793602255;14901;14989.625259368386;14995.700085998436;14996.92501537507;15120.848791511944;12564;12621.87378126207;12628.05289603265;12631.621051368473;12674.746505070032;7930.5638189898955;7933.246341597539;7935.365170786324;7946.205379462008;7984.9201507985335;3284.6058472983395;3320.3857286402094;3323.418401779679;3343.8829640962936;3346.096553930425;908.4685459006423;915.7023967210683;985.6993616946756;1062.7671900595426;1109.5617231193633;28266.85866570654;30141;30480.390392192032;30962.22594435112;31146.22134446657;1036.300497164411;1047.2773786087575;1093.912487001038;1112.1325366214367;1124.606387764276;13517;15695.921520392325;15753.291101900442;15930.884838061225;16177.757333640006;658832.411675877;663792;664226.788660565;668919;670708;50760.49239507632;56164;58259.70870145707;58884.705576471846;58979.70510147422;1197.9460924258428;1352.6753579140936;1363.4682473835212;1365.3924003818206;1379.5792283353474;21175.576488469836;29152.562711559767;29826.85086574553;30078.24804379908;30718.232044199074;38645.2270954585;47540.81147971328;47612.761936190094;47886;47981;519939;570856.1457192854;594693;594729;595936;25745.356366090997;26021;26024.959001639723;26202;26232.213033609023;46755;47784;48186.5181348182;48351.516484834705;48508.2723759151;40063.79968100141;40118.997025074605;40253;40769.1846163081;41309.38035929524;30477.847610761804;37907.62092379041;37914;38578.228435431694;39270.607293926696;37657.05857353588;38721.2255754889;38989.8050509757;39127.217455650156;39951.400728988505;30674.233144171576;34668.740310946865;34702;34898.47652285269;35144;24194.395140121636;25091.498170036863;25970.87014564915;26090.217293481226;26348.20955371342 12:10:27 INFO - PROCESS | 3090 | |2;jquery.html;373.2479054704769;378.6213786213786;385.649059355986;388.10154492350205;412.98761037168896;16510.587235319123;19762.70355944662;21124.683129753026;21723.782762172374;21910.671339929893;20296.695549566768;20391.59216815663;20499.282525111623;20668.68996965043;20775;5065.189569668853;5876.265466816659;5907.793227237047;6070.332263451017;6113.6943152842405;6038.758449662008;6052.606580572271;6091.14723938649;6091.939080609203;6092.11664308675;5811.430913653311;6017.187679663233;6041.214642096544;6084.239470066242;6088.086786981966;5791.276090488689;6012.699365031731;6041.879162416749;6066.636001839882;6083.513318934474;5148.407933087706;5160.426070048634;5266.7629956652045;5279.52484276417;5286.180642000496;23714.762852371427;24667.62998555023;24923.750762492506;25180.622290665655;25232.873835630704;2069.1619893942975;2141.682865038006;2179.52050548879;2274.613315736329;2277.7380601230907;9936.652217172365;10136.189104871593;10344.017318354745;10431.634892778793;10648.680539583747;1935.9516012099668;1951.2975328881591;1961.9509512262166;1996.8302694271003;2002.4793553676004;104364;111698;112047.83736894467;112062;112198.31702524469;11346.262492937933;11428.657140285804;11457.885421145682;11527.827082593767;11710.590129345432;14304.642383940276;14514.491992780413;14536.709265814625;14554.12675239489;14603.415863365559;8490.702825401082;8495.830083398421;8534.74395768128;8594;8612;8380.53907035119;8438.113998030241;8454.577271136419;8595.656173753105;8613.61238744258;3789.9621003789607;3813.7139714521522;4434.933475997863;4496.662750293741;4539.818407263672;1897.2411035585876;1951.5218771401098;1953.4823271832975;1972.8914909680104;1973.2600274896913;774.9573773442402;807.7011505742913;829.3365307753772;853.863381858912;880.8854848869606;17997.730034049237;20524;21146.259880904403;21377.09768018322;21458.78541214599;902.5126431726794;923.4782347973373;946.9526523673788;948.0519480519481;952.2619969523536;7087.1849737279845;8298;9516.00081991381;11332.830007549894;11360.829587556193;546258;559284;566216;566735;572450;78745.42509149785;80659.38681226343;109800;118284.40857795656;121200.85546960765;847.8680960917121;962.8844538655266;1043.786023865121;1048.2190767877828;1049.742813010802;19331.80668193356;19524.414267571996;19548.511287217934;20090.698639520127;20316.984150792698;46264.768676156404;52261.216081758044;53809.46190538201;57125;58541.4145858536;52178.73910630422;74718;77635.61182194053;79648.60175699084;80676;8150.714724984834;8190.672373105247;8198.877016844872;8399.202075802788;8427.705030323908;10492.580296788043;12838.614841554769;12861.356932153541;13059.869401305865;13267.734645307233;10933.398663073607;11281.661550153509;11401.315921044765;11683.88316116828;11919.225250358699;10357.274990750575;10805.783884322112;10965.56137754481;11202.83195752048;11459.31244125355;11449.713757156138;11709.126539753599;11763.294202347886;11777.823332649845;11819.468123934272;8258.174182581934;8261.504309741435;8274.627641756257;8322.417430779788;8412.663493460193;8762.912370876209;10980.396078215776;10990.615328463466;11328.207025508136;11389.658310250707 12:10:27 INFO - PROCESS | 3090 | |3;mootools.html;1384.5777038003407;1418.1987177244855;1454.3092031285141;1831.9816801831978;1877.305397003109;8439;8712.695055673054;8911.153440423157;9534.90465095349;9573.18627916627;8893;9186.402883812547;9225.584848681809;9348.298877584191;9352.485613291266;2467.309153437036;2492.6136448850457;2493.650888875559;2500.562401579716;2514.396544829237;2410.795082417988;2478.6529885816;2481.62775583663;2481.9379515512164;2487.639292302615;2438.6220135878966;2471.950560988779;2503.036331012555;2504.8622325772076;2513.5726926422553;2450.2281781238853;2453.0065323543904;2472.55217334839;2473.332200305925;2477.352560547234;2443.8167137464584;2465.7410971847876;2467.481828815951;2490.116008816862;2491.3397949543387;9097.63609455621;9125.5437228139;9228;9274.211692006187;9286.860697089549;5446.2375267462585;5561.110222364402;5605.495505404533;5626.859328516779;5627.324721033461;3153.880372467787;3216.9517457237926;3281.507773833944;3478.2000139967695;3788.488554045196;970.8883478400004;1026.743314171457;1042.1975079188983;1140.4012893231038;1818.945431637053;11992.580259690869;12112.394380281039;12231.204971676812;12254.754904901942;12297.385130743427;4455.732656040648;4721.102990431807;4792.017636384533;4795.232762757942;4815.614750819926;8588.699395521251;9080.81838363229;9441.669541566149;9527.190188833889;9556.856647150298;5054.913193663307;5453.072977593819;5460.071787796005;5460.808871689472;5463.262459567987;4610.884727881763;4675.275332323496;4780.569748722632;4926.1379258629595;5054.69671819692;3809.8857034289017;3882.126521532689;3937.074787424953;3947.0921688011917;4024.4164596133396;1181.8345431639577;1194.4445832687843;1196.216478206776;1201.3272567362305;1204.3978010994504;892.6875593542338;897.582624079806;901.9729408117767;904.2991681446799;935.9017303183203;5998.4601385875485;6012.30858451275;6124.969375153185;6214.223222097238;6249.6562689052535;436.80998765537095;437.94525684289465;453.272497641281;453.3064411450486;458.3125312031952;3866.7293289469467;4018.1561872007205;4042.8382864685673;4159.1265834175165;4190.350495673176;10965.396903170242;11116.944415277872;11244.66266012021;11353.26203796767;11358.034567061726;10777.083947864363;11215.271007384494;11312.264702794291;11313.71715707114;11325.037371823488;8355.373346999;8657.783555411039;8681.262092722063;8686.34852386061;8710.869336959951;5990.31111422192;6089.634621922699;6110.236220472441;6125.1424800527075;6171.59884607499;9871.703848884545;10426.791464170827;10495.947520262349;10512.369257844553;10573.841392379274;10460.69241344832;10586.417747023988;10591.576336946744;10602.363858168535;10643.988821061987;7188.6046267455795;7393.704251829867;7662.885056724265;7714.344280735976;7884.645190966535;8533.487990720576;8554.615042322986;8658.960924689078;8738.737837864874;8762.123787621442;7681.42389320792;7864.567448790372;7881.960590197242;7950.4832185907735;7962;7585.469017168745;7692.34615057704;7701.037370328709;7736.497127686683;7744.6902123914415;7186.389156921511;7306.232845551141;7310.342069213796;7310.926890731024;7323.597202153933;4464.24107901651;4989.326440930428;5095.617828662939;5101.61261315276;5127.359080114986;5013.323201367769;5018.146915024382;5144.897102057863;5146.073706732824;5182.222666600041 12:10:27 INFO - PROCESS | 3090 | |4;prototype.html;305.35265237696086;308.2647884794764;316.40673736743605;319.69948248646284;322.242729585474;5125.719539494727;5398.568114550836;5759.4816466518005;5865.882682346356;5866.735996880146;4504.301833215849;4796.544328288807;5045.64680472367;5620.550355971523;5734.483896449318;3593.3531964246426;3954.2684603348403;4035.354343305072;4343.6959412841115;4345.782710864444;3282.097423208613;3625.4380571011534;4279.03721662625;4303.720258183223;4346.979238978968;3631.963680363189;4228.5560016198315;4236.809343579531;4268.807903644328;4272.188284226003;3311.784733992295;4250.022494826192;4269.594388533084;4310.245707001262;4328.805203765838;3122.188231059918;3694.2980833641523;3700.4634328022557;3745.8013435700586;3769.492203118747;4589.770511474413;4882.679777751163;5343.706096164709;5397.325334333208;5564.10974244123;4930.827421040246;4958.727240021604;4964.826231081895;4971.37857767779;4986.4265609455015;5209.7134657593815;5666.943330566683;5993.640381577119;6026.517878569748;6029.728662210211;3959.229128899669;4053.8783836484954;4081.3061779497557;4085.5710150434074;4109.4246805447265;5077.746112694351;6220.720067596969;6227.657478838662;6258.71835767387;6286.057091436275;3488.337215928965;3615.2769446110883;4158.731586865974;4207.558206388285;4215.494140703136;3116.1119081062243;3915.686745060388;4134;4185.121124563876;4223.5428777072475;3885.4754608128105;3907.843686252575;4469.122968353299;4483.036147228361;4837.927431088537;5777.62445441045;5971.98476259037;6088.969555152196;6099.346784148909;6148.323684394713;1224.8591411987557;1293.792993121096;1350.8986825987893;1405.5713007532597;1418.5177039806515;717.4009701898973;824.6228797907509;851.7572491839796;865.8831057807115;870.3463419502995;4235.512916014636;4349.652027837766;4409.971413149923;4693.573691205371;4748.646635708807;4678;4801.591864691471;5139.306193663882;5159.7678104485385;5165.405978312542;4182.456280683553;4470.764617691155;4645.606318104555;4685.750839597013;4755.762211889391;2282.463621049052;2456.36134605004;2596.7533084356955;2686.9328266793486;2698.8110832241555;4749.667523273407;5065.189569668835;5196.60955960255;5245.7771493946975;5449.4005659377435;4683.360823711673;4720.409948756405;4844.996102416524;5055.393352797688;5192.9740351298005;4720.159137936139;4727.290906364073;4833.564979151894;5296.947030529645;5328.6802791832615;4377.781110944504;4413.955860441483;4703.506131856106;4718.363020992122;5164.32863727713;4652.32541281519;4725.36207611968;5099.949000510096;5126.231065340229;5135.35808023997;4258.318669012943;4544.863654090383;5070.923936140887;5310.946890531045;5347.919781203363;3484.0593039878586;3513.8418771154847;3589.5513060867393;3853.9016380331473;3983.581723918947;5912.970435147969;6029.397060293935;6126.908096378469;6174.506039516918;6186.659204029774;5308.302152594904;5847.5906686533235;5992.910106348496;6010.87978240424;6106.572539922163;4903.779329930233;5147.176451767699;5558.388577256579;5598.552115830642;5884.499817515559;4987.351644286219;5188.6250143711195;5402.324709411324;5814.592978491465;5910.290765108214;4646.767661616973;5523.8066667666435;5959.255093113361;6020.8259389418645;6129.754809807558;4687.67186296956;5104.417630534546;5227.372715274039;5265.578293860713;5924.555658325557 12:10:27 INFO - PROCESS | 3090 | |5;yui.html;724.4494184419846;737.760227925924;738.4830618567001;773.1379511844292;845.636376358166;15507.689846203084;16502.17489125545;16700.91649541752;16704.665906681857;16721.916390418046;15140.394384224644;15239.92380038101;15258.618534536643;15266.313015914282;15267.312970916308;710.6020628448073;732.4726197138052;867.4665080975218;900.4372267332917;902.8284625921054;845.3955421873359;892.0276898180981;892.7277180459957;893.566620189211;898.2679116520048;909.4770506958492;909.6816114359954;946.0964778636386;947.5309721687775;947.6730527967875;905.5155491811882;930.1349744737391;940.7554035950702;941.9670311539131;943.2878176976408;690.0752990992045;702.3152426384286;706.2584286499207;707.7275249029161;708.649218636773;15060.54818355451;15363.385464581403;15369.692606147926;15395.846041539553;15444.5366639001;14902.850971490254;14906.478273260493;14931.701365972729;14959.326830292663;14970.401183952628;12053.819192712117;12123.696907577292;12153;12284.938575307156;12373.319467429288;3969.3053715599654;4040.5757395473347;4071.0229544909303;4188.078622703;4199.349100889367;117345;117559;118282.81717182804;120756.39621801923;121997.78002220042;8672.56637168139;9625.51872406377;11277.71805704864;11592.362420066976;11838.763224735458;23274.767252327598;23485.647715284285;23515.294541163792;23577.642235776628;23669.881650591637;8148.877766833502;8194.385421093442;8204.384671149688;8225.424220304521;8232.794180145544;4337.4795024596615;4453.398791163217;4470.843520476833;4517.006258623098;4620.052889157714;2934.60382848317;3598.7660802047785;3615.746897717187;3659.8902032939054;3660.762050466711;615.187951903483;654.751194546079;655.6951017776661;663.7444583835162;669.6450881033061;534.6604905884791;671.1442910289322;674.4151244575208;683.3883674111643;693.5116364685639;7550.6602202901;9054.773630659154;9759.756006099904;10006.599736010623;10749.946250268698;607.5989846701156;654.4404534123352;700.3381804195088;700.770148389573;714.7927101140715;3943;4646.442426908792;4716.702906700685;4733.5266473352385;4767.73777442237;129512.70487295194;154696;154942;158698;158933.4373296929;81774;126678;128501.35749321194;128790;130355.34822325828;94542;95978.52010740041;96650.99999999859;111550;120048.99877502957;9746.951265243628;10109.39343639384;10141.326681097536;10156.949215254172;10433.478326083818;50934;58197.4180258192;59347.81304373975;59825.40174598373;61436.69281653563;74007.1498212549;83164.58417707872;88691;92360.996535225;95478.52260738652;12499.687507812378;13173.93413032967;13280.53518126861;13364.264965426606;13385.063045586716;14878;14921.701565968837;14956.700865982837;15248.771268431205;15314.234288285765;13979.301034948416;14123.64690882695;14161.433542658178;14205.28973551339;14764.557063288119;11594.362310073027;14107.929460352632;14235.932305076953;14758.852411476177;14797.482088126864;13667.111637743516;13717.245551494365;13743.635819107756;13829.170249784642;14111;10855.565777369133;11141.108711303239;11172.832407514057;11391.943040284745;11662.300261984308;10127.79744405093;11334.119250493935;11677.88322116768;11678.824817627572;11743.588974385855 12:10:27 INFO - PROCESS | 3090 | __end_tp_report 12:10:27 INFO - PROCESS | 3090 | __start_cc_report 12:10:27 INFO - PROCESS | 3090 | _x_x_mozilla_cycle_collect,347 12:10:27 INFO - PROCESS | 3090 | __end_cc_report 12:10:27 INFO - PROCESS | 3090 | __startTimestamp1474917027574__endTimestamp 12:10:27 INFO - PROCESS | 3090 | 12:10:27 INFO - PROCESS | 3090 | ------- Summary: start ------- 12:10:27 INFO - PROCESS | 3090 | Number of tests: 6 12:10:27 INFO - PROCESS | 3090 | 12:10:27 INFO - PROCESS | 3090 | [#0] dojo.html Cycles:165 Average:5894.51 Median:6166.97 stddev:700.52 (11.4%) stddev-sans-first:670.74 12:10:27 INFO - PROCESS | 3090 | Values: 3229.0 3265.7 3307.1 3312.1 3324.1 5959.9 5987.8 6009.1 6020.7 6088.7 6002.9 6009.1 6045.1 6067.9 6099.1 5364.5 5407.4 5448.2 5451.3 5508.0 5468.8 5544.2 5565.8 5587.7 5590.7 5385.3 5409.0 5503.3 5521.0 5536.4 4106.0 5399.7 5456.9 5521.2 5553.7 5360.7 5385.3 5414.0 5419.4 5444.3 5260.0 5557.8 5941.6 5944.7 6167.4 4834.6 5554.7 5775.2 6125.7 6306.6 6486.8 6542.4 6553.5 6558.0 6566.9 6178.3 6195.7 6209.6 6210.4 6213.9 6309.9 6465.7 6467.4 6509.5 6535.3 5213.8 6254.2 6329.3 6332.8 6335.9 5502.9 6137.4 6418.0 6471.5 6507.7 5055.8 6229.3 6231.4 6273.1 6286.9 5488.8 6302.8 6371.1 6412.4 6414.6 4446.2 4667.8 5201.1 5214.2 5221.0 4493.3 4561.3 4581.0 4668.8 4676.6 6104.3 6111.2 6121.0 6167.0 6177.8 6495.1 6514.3 6522.3 6525.1 6536.6 5451.3 6087.9 6118.6 6156.2 6172.2 5646.0 5685.1 5744.2 5769.6 5815.1 6553.5 6564.7 6597.3 6599.3 6622.6 6234.2 6386.6 6407.0 6407.2 6421.3 6519.9 6540.6 6549.9 6553.7 6730.3 6395.4 6400.9 6417.7 6421.8 6435.6 5724.3 5815.8 6521.1 6531.1 6546.4 6126.8 6170.1 6171.2 6183.2 6223.4 5795.9 6257.4 6262.7 6281.7 6292.7 5996.7 6123.7 6173.8 6181.9 6212.7 6211.3 6212.0 6241.1 6331.6 6346.8 5253.2 6191.6 6205.1 6235.6 6239.6 12:10:27 INFO - PROCESS | 3090 | 12:10:27 INFO - PROCESS | 3090 | [#1] ext.html Cycles:180 Average:68900.48 Median:40091.40 stddev:154586.78 (385.6%) stddev-sans-first:154935.20 12:10:27 INFO - PROCESS | 3090 | Values: 530.6 542.6 543.4 543.9 560.2 36643.6 42093.0 42927.8 43176.4 43819.1 33467.0 40052.0 42923.8 45624.9 46043.0 8398.4 9713.6 9883.1 9906.0 9915.9 8517.2 8554.4 9473.3 9643.9 9725.0 9741.4 9741.8 9753.7 9764.2 9800.8 9628.6 9701.7 9702.5 9715.8 9790.5 9697.7 9718.5 9728.6 9758.7 9762.9 40935.4 41249.4 41304.0 41336.6 41342.6 3554.3 3593.0 3704.0 4024.5 4053.4 27342.7 31580.4 31957.8 32142.4 32921.7 3327.9 3344.6 3354.8 3413.8 3471.1 402583.0 478713.0 492582.0 492771.0 494431.5 34025.8 56320.7 56621.4 56721.4 56737.4 35127.6 35721.8 35912.3 36012.7 36020.6 14901.0 14989.6 14995.7 14996.9 15120.8 12564.0 12621.9 12628.1 12631.6 12674.7 7930.6 7933.2 7935.4 7946.2 7984.9 3284.6 3320.4 3323.4 3343.9 3346.1 908.5 915.7 985.7 1062.8 1109.6 28266.9 30141.0 30480.4 30962.2 31146.2 1036.3 1047.3 1093.9 1112.1 1124.6 13517.0 15695.9 15753.3 15930.9 16177.8 658832.4 663792.0 664226.8 668919.0 670708.0 50760.5 56164.0 58259.7 58884.7 58979.7 1197.9 1352.7 1363.5 1365.4 1379.6 21175.6 29152.6 29826.9 30078.2 30718.2 38645.2 47540.8 47612.8 47886.0 47981.0 519939.0 570856.1 594693.0 594729.0 595936.0 25745.4 26021.0 26025.0 26202.0 26232.2 46755.0 47784.0 48186.5 48351.5 48508.3 40063.8 40119.0 40253.0 40769.2 41309.4 30477.8 37907.6 37914.0 38578.2 39270.6 37657.1 38721.2 38989.8 39127.2 39951.4 30674.2 34668.7 34702.0 34898.5 35144.0 24194.4 25091.5 25970.9 26090.2 26348.2 12:10:27 INFO - PROCESS | 3090 | 12:10:27 INFO - PROCESS | 3090 | [#2] jquery.html Cycles:180 Average:32945.29 Median:12803.06 stddev:93390.77 (729.4%) stddev-sans-first:93620.73 12:10:27 INFO - PROCESS | 3090 | Values: 373.2 378.6 385.6 388.1 413.0 16510.6 19762.7 21124.7 21723.8 21910.7 20296.7 20391.6 20499.3 20668.7 20775.0 5065.2 5876.3 5907.8 6070.3 6113.7 6038.8 6052.6 6091.1 6091.9 6092.1 5811.4 6017.2 6041.2 6084.2 6088.1 5791.3 6012.7 6041.9 6066.6 6083.5 5148.4 5160.4 5266.8 5279.5 5286.2 23714.8 24667.6 24923.8 25180.6 25232.9 2069.2 2141.7 2179.5 2274.6 2277.7 9936.7 10136.2 10344.0 10431.6 10648.7 1936.0 1951.3 1962.0 1996.8 2002.5 104364.0 111698.0 112047.8 112062.0 112198.3 11346.3 11428.7 11457.9 11527.8 11710.6 14304.6 14514.5 14536.7 14554.1 14603.4 8490.7 8495.8 8534.7 8594.0 8612.0 8380.5 8438.1 8454.6 8595.7 8613.6 3790.0 3813.7 4434.9 4496.7 4539.8 1897.2 1951.5 1953.5 1972.9 1973.3 775.0 807.7 829.3 853.9 880.9 17997.7 20524.0 21146.3 21377.1 21458.8 902.5 923.5 947.0 948.1 952.3 7087.2 8298.0 9516.0 11332.8 11360.8 546258.0 559284.0 566216.0 566735.0 572450.0 78745.4 80659.4 109800.0 118284.4 121200.9 847.9 962.9 1043.8 1048.2 1049.7 19331.8 19524.4 19548.5 20090.7 20317.0 46264.8 52261.2 53809.5 57125.0 58541.4 52178.7 74718.0 77635.6 79648.6 80676.0 8150.7 8190.7 8198.9 8399.2 8427.7 10492.6 12838.6 12861.4 13059.9 13267.7 10933.4 11281.7 11401.3 11683.9 11919.2 10357.3 10805.8 10965.6 11202.8 11459.3 11449.7 11709.1 11763.3 11777.8 11819.5 8258.2 8261.5 8274.6 8322.4 8412.7 8762.9 10980.4 10990.6 11328.2 11389.7 12:10:27 INFO - PROCESS | 3090 | 12:10:27 INFO - PROCESS | 3090 | [#3] mootools.html Cycles:180 Average:5881.31 Median:4805.42 stddev:3351.90 (69.8%) stddev-sans-first:3344.27 12:10:27 INFO - PROCESS | 3090 | Values: 1384.6 1418.2 1454.3 1832.0 1877.3 8439.0 8712.7 8911.2 9534.9 9573.2 8893.0 9186.4 9225.6 9348.3 9352.5 2467.3 2492.6 2493.7 2500.6 2514.4 2410.8 2478.7 2481.6 2481.9 2487.6 2438.6 2472.0 2503.0 2504.9 2513.6 2450.2 2453.0 2472.6 2473.3 2477.4 2443.8 2465.7 2467.5 2490.1 2491.3 9097.6 9125.5 9228.0 9274.2 9286.9 5446.2 5561.1 5605.5 5626.9 5627.3 3153.9 3217.0 3281.5 3478.2 3788.5 970.9 1026.7 1042.2 1140.4 1818.9 11992.6 12112.4 12231.2 12254.8 12297.4 4455.7 4721.1 4792.0 4795.2 4815.6 8588.7 9080.8 9441.7 9527.2 9556.9 5054.9 5453.1 5460.1 5460.8 5463.3 4610.9 4675.3 4780.6 4926.1 5054.7 3809.9 3882.1 3937.1 3947.1 4024.4 1181.8 1194.4 1196.2 1201.3 1204.4 892.7 897.6 902.0 904.3 935.9 5998.5 6012.3 6125.0 6214.2 6249.7 436.8 437.9 453.3 453.3 458.3 3866.7 4018.2 4042.8 4159.1 4190.4 10965.4 11116.9 11244.7 11353.3 11358.0 10777.1 11215.3 11312.3 11313.7 11325.0 8355.4 8657.8 8681.3 8686.3 8710.9 5990.3 6089.6 6110.2 6125.1 6171.6 9871.7 10426.8 10495.9 10512.4 10573.8 10460.7 10586.4 10591.6 10602.4 10644.0 7188.6 7393.7 7662.9 7714.3 7884.6 8533.5 8554.6 8659.0 8738.7 8762.1 7681.4 7864.6 7882.0 7950.5 7962.0 7585.5 7692.3 7701.0 7736.5 7744.7 7186.4 7306.2 7310.3 7310.9 7323.6 4464.2 4989.3 5095.6 5101.6 5127.4 5013.3 5018.1 5144.9 5146.1 5182.2 12:10:27 INFO - PROCESS | 3090 | 12:10:27 INFO - PROCESS | 3090 | [#4] prototype.html Cycles:180 Average:4465.45 Median:4752.71 stddev:1389.21 (29.2%) stddev-sans-first:1357.56 12:10:27 INFO - PROCESS | 3090 | Values: 305.4 308.3 316.4 319.7 322.2 5125.7 5398.6 5759.5 5865.9 5866.7 4504.3 4796.5 5045.6 5620.6 5734.5 3593.4 3954.3 4035.4 4343.7 4345.8 3282.1 3625.4 4279.0 4303.7 4347.0 3632.0 4228.6 4236.8 4268.8 4272.2 3311.8 4250.0 4269.6 4310.2 4328.8 3122.2 3694.3 3700.5 3745.8 3769.5 4589.8 4882.7 5343.7 5397.3 5564.1 4930.8 4958.7 4964.8 4971.4 4986.4 5209.7 5666.9 5993.6 6026.5 6029.7 3959.2 4053.9 4081.3 4085.6 4109.4 5077.7 6220.7 6227.7 6258.7 6286.1 3488.3 3615.3 4158.7 4207.6 4215.5 3116.1 3915.7 4134.0 4185.1 4223.5 3885.5 3907.8 4469.1 4483.0 4837.9 5777.6 5972.0 6089.0 6099.3 6148.3 1224.9 1293.8 1350.9 1405.6 1418.5 717.4 824.6 851.8 865.9 870.3 4235.5 4349.7 4410.0 4693.6 4748.6 4678.0 4801.6 5139.3 5159.8 5165.4 4182.5 4470.8 4645.6 4685.8 4755.8 2282.5 2456.4 2596.8 2686.9 2698.8 4749.7 5065.2 5196.6 5245.8 5449.4 4683.4 4720.4 4845.0 5055.4 5193.0 4720.2 4727.3 4833.6 5296.9 5328.7 4377.8 4414.0 4703.5 4718.4 5164.3 4652.3 4725.4 5099.9 5126.2 5135.4 4258.3 4544.9 5070.9 5310.9 5347.9 3484.1 3513.8 3589.6 3853.9 3983.6 5913.0 6029.4 6126.9 6174.5 6186.7 5308.3 5847.6 5992.9 6010.9 6106.6 4903.8 5147.2 5558.4 5598.6 5884.5 4987.4 5188.6 5402.3 5814.6 5910.3 4646.8 5523.8 5959.3 6020.8 6129.8 4687.7 5104.4 5227.4 5265.6 5924.6 12:10:27 INFO - PROCESS | 3090 | 12:10:27 INFO - PROCESS | 3090 | [#5] yui.html Cycles:180 Average:24737.34 Median:3266.68 stddev:39178.63 (1199.3%) stddev-sans-first:39247.05 12:10:27 INFO - PROCESS | 3090 | Values: 724.4 737.8 738.5 773.1 845.6 15507.7 16502.2 16700.9 16704.7 16721.9 15140.4 15239.9 15258.6 15266.3 15267.3 710.6 732.5 867.5 900.4 902.8 845.4 892.0 892.7 893.6 898.3 909.5 909.7 946.1 947.5 947.7 905.5 930.1 940.8 942.0 943.3 690.1 702.3 706.3 707.7 708.6 15060.5 15363.4 15369.7 15395.8 15444.5 14902.9 14906.5 14931.7 14959.3 14970.4 12053.8 12123.7 12153.0 12284.9 12373.3 3969.3 4040.6 4071.0 4188.1 4199.3 117345.0 117559.0 118282.8 120756.4 121997.8 8672.6 9625.5 11277.7 11592.4 11838.8 23274.8 23485.6 23515.3 23577.6 23669.9 8148.9 8194.4 8204.4 8225.4 8232.8 4337.5 4453.4 4470.8 4517.0 4620.1 2934.6 3598.8 3615.7 3659.9 3660.8 615.2 654.8 655.7 663.7 669.6 534.7 671.1 674.4 683.4 693.5 7550.7 9054.8 9759.8 10006.6 10749.9 607.6 654.4 700.3 700.8 714.8 3943.0 4646.4 4716.7 4733.5 4767.7 129512.7 154696.0 154942.0 158698.0 158933.4 81774.0 126678.0 128501.4 128790.0 130355.3 94542.0 95978.5 96651.0 111550.0 120049.0 9747.0 10109.4 10141.3 10156.9 10433.5 50934.0 58197.4 59347.8 59825.4 61436.7 74007.1 83164.6 88691.0 92361.0 95478.5 12499.7 13173.9 13280.5 13364.3 13385.1 14878.0 14921.7 14956.7 15248.8 15314.2 13979.3 14123.6 14161.4 14205.3 14764.6 11594.4 14107.9 14235.9 14758.9 14797.5 13667.1 13717.2 13743.6 13829.2 14111.0 10855.6 11141.1 11172.8 11391.9 11662.3 10127.8 11334.1 11677.9 11678.8 11743.6 12:10:27 INFO - PROCESS | 3090 | -------- Summary: end -------- 12:10:27 INFO - PROCESS | 3090 | 12:10:28 INFO - TEST-INFO | 3090: exit 0 12:10:28 INFO - TEST-OK | dromaeo_css | took 1114141ms 12:10:28 INFO - TEST-START | kraken 12:10:28 INFO - operating with platform_type : mac_ 12:10:28 INFO - Initialising browser for kraken test... 12:10:28 INFO - TEST-INFO | started process 3097 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpx0U7zp/profile http://localhost:52760/getInfo.html) 12:10:30 INFO - PROCESS | 3097 | 2016-09-26 12:10:30.844 plugin-container[3098:24672] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b23, name = 'com.apple.tsm.portname' 12:10:30 INFO - PROCESS | 3097 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:10:30 INFO - PROCESS | 3097 | 2016-09-26 12:10:30.847 plugin-container[3098:24672] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 12:10:30 INFO - PROCESS | 3097 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:10:30 INFO - PROCESS | 3097 | 2016-09-26 12:10:30.847 plugin-container[3098:24672] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:10:30 INFO - PROCESS | 3097 | __metrics Screen width/height:1600/1200 12:10:30 INFO - PROCESS | 3097 | colorDepth:24 12:10:30 INFO - PROCESS | 3097 | Browser inner width/height: 1024/689 12:10:30 INFO - PROCESS | 3097 | __metrics 12:10:31 ERROR - PROCESS | 3097 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 12:10:31 INFO - TEST-INFO | 3097: exit 0 12:10:31 INFO - Browser initialized. 12:10:31 INFO - Running cycle 1/1 for kraken test... 12:10:31 INFO - TEST-INFO | started process 3099 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpx0U7zp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:10:34 INFO - PROCESS | 3099 | 2016-09-26 12:10:34.044 plugin-container[3100:24786] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8527, name = 'com.apple.tsm.portname' 12:10:34 INFO - PROCESS | 3099 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:10:34 INFO - PROCESS | 3099 | 2016-09-26 12:10:34.046 plugin-container[3100:24786] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d03, name = 'com.apple.CFPasteboardClient' 12:10:34 INFO - PROCESS | 3099 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:10:34 INFO - PROCESS | 3099 | 2016-09-26 12:10:34.046 plugin-container[3100:24786] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:10:43 INFO - PROCESS | 3099 | RSS: Main: 210939904 12:10:43 INFO - PROCESS | 3099 | 12:10:47 INFO - PROCESS | 3099 | Cycle 1(1): loaded http://localhost:52760/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52760/tests/kraken/driver.html?testName=audio-beat-detection) 12:10:47 INFO - PROCESS | 3099 | RSS: Main: 224677888 12:10:47 INFO - PROCESS | 3099 | 12:10:50 INFO - PROCESS | 3099 | Cycle 1(1): loaded http://localhost:52760/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52760/tests/kraken/driver.html?testName=audio-dft) 12:10:51 INFO - PROCESS | 3099 | RSS: Main: 232210432 12:10:51 INFO - PROCESS | 3099 | 12:10:54 INFO - PROCESS | 3099 | Cycle 1(1): loaded http://localhost:52760/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52760/tests/kraken/driver.html?testName=audio-fft) 12:10:55 INFO - PROCESS | 3099 | RSS: Main: 233037824 12:10:55 INFO - PROCESS | 3099 | 12:10:57 INFO - PROCESS | 3099 | Cycle 1(1): loaded http://localhost:52760/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52760/tests/kraken/driver.html?testName=audio-oscillator) 12:10:58 INFO - PROCESS | 3099 | RSS: Main: 235261952 12:10:58 INFO - PROCESS | 3099 | 12:10:59 INFO - PROCESS | 3099 | Cycle 1(1): loaded http://localhost:52760/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52760/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:11:00 INFO - PROCESS | 3099 | RSS: Main: 235589632 12:11:00 INFO - PROCESS | 3099 | 12:11:03 INFO - PROCESS | 3099 | Cycle 1(1): loaded http://localhost:52760/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52760/tests/kraken/driver.html?testName=imaging-darkroom) 12:11:03 INFO - PROCESS | 3099 | RSS: Main: 236396544 12:11:03 INFO - PROCESS | 3099 | 12:11:06 INFO - PROCESS | 3099 | Cycle 1(1): loaded http://localhost:52760/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52760/tests/kraken/driver.html?testName=imaging-desaturate) 12:11:07 INFO - PROCESS | 3099 | RSS: Main: 236662784 12:11:07 INFO - PROCESS | 3099 | 12:11:10 INFO - PROCESS | 3099 | Cycle 1(1): loaded http://localhost:52760/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52760/tests/kraken/driver.html?testName=json-parse-financial) 12:11:10 INFO - PROCESS | 3099 | RSS: Main: 236810240 12:11:10 INFO - PROCESS | 3099 | 12:11:12 INFO - PROCESS | 3099 | Cycle 1(1): loaded http://localhost:52760/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52760/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:11:13 INFO - PROCESS | 3099 | RSS: Main: 236527616 12:11:13 INFO - PROCESS | 3099 | 12:11:15 INFO - PROCESS | 3099 | Cycle 1(1): loaded http://localhost:52760/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52760/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:11:15 INFO - PROCESS | 3099 | RSS: Main: 236580864 12:11:15 INFO - PROCESS | 3099 | 12:11:17 INFO - PROCESS | 3099 | Cycle 1(1): loaded http://localhost:52760/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52760/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:11:18 INFO - PROCESS | 3099 | RSS: Main: 236650496 12:11:18 INFO - PROCESS | 3099 | 12:11:21 INFO - PROCESS | 3099 | Cycle 1(1): loaded http://localhost:52760/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52760/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:11:22 INFO - PROCESS | 3099 | RSS: Main: 236920832 12:11:22 INFO - PROCESS | 3099 | 12:11:25 INFO - PROCESS | 3099 | Cycle 1(1): loaded http://localhost:52760/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52760/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:11:25 INFO - PROCESS | 3099 | RSS: Main: 236953600 12:11:25 INFO - PROCESS | 3099 | 12:11:27 INFO - PROCESS | 3099 | Cycle 1(1): loaded http://localhost:52760/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52760/tests/kraken/driver.html?testName=ai-astar) 12:11:27 INFO - PROCESS | 3099 | RSS: Main: 238690304 12:11:27 INFO - PROCESS | 3099 | 12:11:27 INFO - PROCESS | 3099 | __start_tp_report 12:11:27 INFO - PROCESS | 3099 | _x_x_mozilla_page_load 12:11:27 INFO - PROCESS | 3099 | _x_x_mozilla_page_load_details 12:11:27 INFO - PROCESS | 3099 | |i|pagename|runs| 12:11:27 INFO - PROCESS | 3099 | |0;ai-astar;104;116;137;171;149;159;172;174;117;121 12:11:27 INFO - PROCESS | 3099 | |1;audio-beat-detection;141;110;114;182;134;112;111;110;158;140 12:11:27 INFO - PROCESS | 3099 | |2;audio-dft;215;223;278;282;198;195;197;197;195;198 12:11:27 INFO - PROCESS | 3099 | |3;audio-fft;99;82;83;109;73;59;61;60;77;60 12:11:27 INFO - PROCESS | 3099 | |4;audio-oscillator;89;71;86;72;68;67;89;70;73;87 12:11:27 INFO - PROCESS | 3099 | |5;imaging-gaussian-blur;90;89;90;88;103;87;92;88;89;88 12:11:27 INFO - PROCESS | 3099 | |6;imaging-darkroom;96;98;95;160;97;96;105;101;149;149 12:11:27 INFO - PROCESS | 3099 | |7;imaging-desaturate;99;91;90;90;93;90;90;91;91;90 12:11:27 INFO - PROCESS | 3099 | |8;json-parse-financial;58;56;54;55;56;57;56;57;55;66 12:11:27 INFO - PROCESS | 3099 | |9;json-stringify-tinderbox;70;72;71;82;75;68;66;93;105;70 12:11:27 INFO - PROCESS | 3099 | |10;stanford-crypto-aes;87;89;89;89;75;109;107;106;87;94 12:11:27 INFO - PROCESS | 3099 | |11;stanford-crypto-ccm;138;140;145;156;132;155;158;154;173;168 12:11:27 INFO - PROCESS | 3099 | |12;stanford-crypto-pbkdf2;170;171;168;168;169;170;178;153;214;236 12:11:27 INFO - PROCESS | 3099 | |13;stanford-crypto-sha256-iterative;66;70;64;73;70;68;67;64;58;66 12:11:27 INFO - PROCESS | 3099 | __end_tp_report 12:11:27 INFO - PROCESS | 3099 | __start_cc_report 12:11:27 INFO - PROCESS | 3099 | _x_x_mozilla_cycle_collect,903 12:11:27 INFO - PROCESS | 3099 | __end_cc_report 12:11:27 INFO - PROCESS | 3099 | __startTimestamp1474917087821__endTimestamp 12:11:27 INFO - PROCESS | 3099 | 12:11:27 INFO - PROCESS | 3099 | ------- Summary: start ------- 12:11:27 INFO - PROCESS | 3099 | Number of tests: 14 12:11:27 INFO - PROCESS | 3099 | 12:11:27 INFO - PROCESS | 3099 | [#0] ai-astar Cycles:10 Average:142.00 Median:154.00 stddev:26.49 (17.2%) stddev-sans-first:24.26 12:11:27 INFO - PROCESS | 3099 | Values: 104.0 116.0 137.0 171.0 149.0 159.0 172.0 174.0 117.0 121.0 12:11:27 INFO - PROCESS | 3099 | 12:11:27 INFO - PROCESS | 3099 | [#1] audio-beat-detection Cycles:10 Average:131.20 Median:137.00 stddev:24.61 (18.0%) stddev-sans-first:25.85 12:11:27 INFO - PROCESS | 3099 | Values: 141.0 110.0 114.0 182.0 134.0 112.0 111.0 110.0 158.0 140.0 12:11:27 INFO - PROCESS | 3099 | 12:11:27 INFO - PROCESS | 3099 | [#2] audio-dft Cycles:10 Average:217.80 Median:206.50 stddev:34.11 (16.5%) stddev-sans-first:36.16 12:11:27 INFO - PROCESS | 3099 | Values: 215.0 223.0 278.0 282.0 198.0 195.0 197.0 197.0 195.0 198.0 12:11:27 INFO - PROCESS | 3099 | 12:11:27 INFO - PROCESS | 3099 | [#3] audio-fft Cycles:10 Average:76.30 Median:75.00 stddev:17.44 (23.3%) stddev-sans-first:16.45 12:11:27 INFO - PROCESS | 3099 | Values: 99.0 82.0 83.0 109.0 73.0 59.0 61.0 60.0 77.0 60.0 12:11:27 INFO - PROCESS | 3099 | 12:11:27 INFO - PROCESS | 3099 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:79.50 stddev:9.28 (11.7%) stddev-sans-first:8.81 12:11:27 INFO - PROCESS | 3099 | Values: 89.0 71.0 86.0 72.0 68.0 67.0 89.0 70.0 73.0 87.0 12:11:27 INFO - PROCESS | 3099 | 12:11:27 INFO - PROCESS | 3099 | [#5] imaging-gaussian-blur Cycles:10 Average:90.40 Median:89.00 stddev:4.65 (5.2%) stddev-sans-first:4.93 12:11:27 INFO - PROCESS | 3099 | Values: 90.0 89.0 90.0 88.0 103.0 87.0 92.0 88.0 89.0 88.0 12:11:27 INFO - PROCESS | 3099 | 12:11:27 INFO - PROCESS | 3099 | [#6] imaging-darkroom Cycles:10 Average:114.60 Median:95.50 stddev:26.60 (27.8%) stddev-sans-first:27.35 12:11:27 INFO - PROCESS | 3099 | Values: 96.0 98.0 95.0 160.0 97.0 96.0 105.0 101.0 149.0 149.0 12:11:27 INFO - PROCESS | 3099 | 12:11:27 INFO - PROCESS | 3099 | [#7] imaging-desaturate Cycles:10 Average:91.50 Median:91.00 stddev:2.80 (3.1%) stddev-sans-first:1.00 12:11:27 INFO - PROCESS | 3099 | Values: 99.0 91.0 90.0 90.0 93.0 90.0 90.0 91.0 91.0 90.0 12:11:27 INFO - PROCESS | 3099 | 12:11:27 INFO - PROCESS | 3099 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:56.50 stddev:3.37 (6.0%) stddev-sans-first:3.55 12:11:27 INFO - PROCESS | 3099 | Values: 58.0 56.0 54.0 55.0 56.0 57.0 56.0 57.0 55.0 66.0 12:11:27 INFO - PROCESS | 3099 | 12:11:27 INFO - PROCESS | 3099 | [#9] json-stringify-tinderbox Cycles:10 Average:77.20 Median:71.50 stddev:12.60 (17.6%) stddev-sans-first:13.10 12:11:27 INFO - PROCESS | 3099 | Values: 70.0 72.0 71.0 82.0 75.0 68.0 66.0 93.0 105.0 70.0 12:11:27 INFO - PROCESS | 3099 | 12:11:27 INFO - PROCESS | 3099 | [#10] stanford-crypto-aes Cycles:10 Average:93.20 Median:88.00 stddev:10.88 (12.4%) stddev-sans-first:11.31 12:11:27 INFO - PROCESS | 3099 | Values: 87.0 89.0 89.0 89.0 75.0 109.0 107.0 106.0 87.0 94.0 12:11:27 INFO - PROCESS | 3099 | 12:11:27 INFO - PROCESS | 3099 | [#11] stanford-crypto-ccm Cycles:10 Average:151.90 Median:155.50 stddev:13.13 (8.4%) stddev-sans-first:12.92 12:11:27 INFO - PROCESS | 3099 | Values: 138.0 140.0 145.0 156.0 132.0 155.0 158.0 154.0 173.0 168.0 12:11:27 INFO - PROCESS | 3099 | 12:11:27 INFO - PROCESS | 3099 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.70 Median:170.50 stddev:25.20 (14.8%) stddev-sans-first:26.48 12:11:27 INFO - PROCESS | 3099 | Values: 170.0 171.0 168.0 168.0 169.0 170.0 178.0 153.0 214.0 236.0 12:11:27 INFO - PROCESS | 3099 | 12:11:27 INFO - PROCESS | 3099 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.50 stddev:4.14 (6.1%) stddev-sans-first:4.39 12:11:27 INFO - PROCESS | 3099 | Values: 66.0 70.0 64.0 73.0 70.0 68.0 67.0 64.0 58.0 66.0 12:11:27 INFO - PROCESS | 3099 | -------- Summary: end -------- 12:11:27 INFO - PROCESS | 3099 | 12:11:28 INFO - TEST-INFO | 3099: exit 0 12:11:28 INFO - TEST-OK | kraken | took 60499ms 12:11:28 INFO - SUITE-END | took 1174s 12:11:28 INFO - Completed test suite (00:19:34) 12:11:28 INFO - javascript benchmark 12:11:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7318.034240474505, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3229.0474310078525, 3265.7224135948445, 3307.0905500987224, 3312.0726196664914, 3324.0692606070293, 5959.940400595992, 5987.7604895804225, 6009.0685943678745, 6020.729067191976, 6088.695565221732, 6002.8799424011495, 6009.06859436788, 6045.0932360146, 6067.908981365272, 6099.146119543268, 5364.517193452589, 5407.351117865862, 5448.182772584124, 5451.291332126818, 5507.953488837128, 5468.7539060742165, 5544.1960915667205, 5565.777368905239, 5587.664740115606, 5590.720463976805, 5385.272988146589, 5409.0, 5503.284573005523, 5521.0, 5536.390996990328, 4105.958940410617, 5399.676019438807, 5456.945430545684, 5521.2270282160525, 5553.694546799924, 5360.65155764874, 5385.272988146628, 5414.0, 5419.430959749248, 5444.265024221759, 5260.0, 5557.832855100433, 5941.613795103348, 5944.702764861783, 6167.383261673842, 4834.564889159992, 5554.666879948783, 5775.16260142277, 6125.682978159717, 6306.558540902139, 6486.777069302808, 6542.378474044958, 6553.508486863504, 6558.016297555358, 6566.901496477557, 6178.258608966908, 6195.659238741912, 6209.59637623553, 6210.441060304549, 6213.937860621336, 6309.936900630935, 6465.709043093072, 6467.4179323860135, 6509.544331896816, 6535.281119076993, 5213.7914483421, 6254.21822272216, 6329.303776584572, 6332.841678958063, 6335.873282534416, 5502.862428439321, 6137.438273608381, 6418.0, 6471.4822814175695, 6507.739690412331, 5055.848324550269, 6229.345918678474, 6231.376862313732, 6273.12176295319, 6286.874262514815, 5488.792465657549, 6302.8424289392215, 6371.076193951943, 6412.35876412355, 6414.550981431348, 4446.244138496463, 4667.813287468532, 5201.089809283436, 5214.165733482625, 5220.981908527877, 4493.348464472699, 4561.338605902126, 4581.015081757439, 4668.836590719308, 4676.55572720591, 6104.298005729309, 6111.174991376196, 6121.02133613155, 6166.969165154145, 6177.81466556004, 6495.058216558573, 6514.348565143448, 6522.347765223534, 6525.086487891699, 6536.575122617015, 5451.2640793493165, 6087.878242435127, 6118.6022908511695, 6156.199694039746, 6172.197614310201, 5645.971770141123, 5685.118806584986, 5744.195812586241, 5769.624974376652, 5815.127730840323, 6553.541252112402, 6564.67176641166, 6597.27429982692, 6599.340065993362, 6622.569532980245, 6234.189555357774, 6386.648734319657, 6407.0, 6407.167068281094, 6421.293657697743, 6519.902201466887, 6540.607563546202, 6549.8690026200165, 6553.705083271169, 6730.32696730323, 6395.392437718409, 6400.903986440114, 6417.679116044273, 6421.775237866652, 6435.646039467874, 5724.313082430135, 5815.82552523425, 6521.054447105048, 6531.085648009187, 6546.443552298108, 6126.8230438726505, 6170.074488826712, 6171.1668924694595, 6183.196184495987, 6223.408776166257, 5795.8551036224435, 6257.436830685078, 6262.655553944577, 6281.685915704288, 6292.653904035322, 5996.730147143477, 6123.70969670409, 6173.81478555644, 6181.938180618316, 6212.689365531615, 6211.254649442096, 6211.9689401554515, 6241.095041218907, 6331.556791024584, 6346.777862774781, 5253.212018197301, 6191.566590338633, 6205.100260462298, 6235.563510554399, 6239.625622462667], "value": 5849.706538649382, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [530.6046994988733, 542.6120323968364, 543.3642638113407, 543.9075357189278, 560.1513706734297, 36643.633563664356, 42093.0, 42927.785361073154, 43176.352354714705, 43819.12361752763, 33466.995990120275, 40052.0, 42923.78538107305, 45624.85937851555, 46043.0, 8398.412111152222, 9713.61145554177, 9883.061109194616, 9906.0, 9915.90084099157, 8517.190866867637, 8554.35842311829, 9473.28950328728, 9643.855342169873, 9725.0, 9741.415515069119, 9741.805163896717, 9753.658621948232, 9764.168391166553, 9800.803983920317, 9628.614855405775, 9701.708948731513, 9702.51487425626, 9715.757106072335, 9790.461524616143, 9697.6605818796, 9718.514074296258, 9728.61085556584, 9758.658446954394, 9762.853557196648, 40935.38596921019, 41249.38125928114, 41303.967400814916, 41336.58663413357, 41342.586574134475, 3554.253606742587, 3592.982035089834, 3703.9814800925824, 4024.537178224483, 4053.43251944728, 27342.726572734417, 31580.36839263225, 31957.84021079903, 32142.357152856814, 32921.6707832921, 3327.88352407667, 3344.6153692325206, 3354.798712077283, 3413.7951722896705, 3471.062813040465, 402582.98708506563, 478713.0, 492582.0, 492771.0, 494431.52784236206, 34025.82987085049, 56320.718396407756, 56621.43378566244, 56721.43278567244, 56737.43262567322, 35127.648723512946, 35721.82139089288, 35912.28175436477, 36012.739554115484, 36020.639793602255, 14901.0, 14989.625259368386, 14995.700085998436, 14996.92501537507, 15120.848791511944, 12564.0, 12621.87378126207, 12628.05289603265, 12631.621051368473, 12674.746505070032, 7930.5638189898955, 7933.246341597539, 7935.365170786324, 7946.205379462008, 7984.9201507985335, 3284.6058472983395, 3320.3857286402094, 3323.418401779679, 3343.8829640962936, 3346.096553930425, 908.4685459006423, 915.7023967210683, 985.6993616946756, 1062.7671900595426, 1109.5617231193633, 28266.85866570654, 30141.0, 30480.390392192032, 30962.22594435112, 31146.22134446657, 1036.300497164411, 1047.2773786087575, 1093.912487001038, 1112.1325366214367, 1124.606387764276, 13517.0, 15695.921520392325, 15753.291101900442, 15930.884838061225, 16177.757333640006, 658832.411675877, 663792.0, 664226.788660565, 668919.0, 670708.0, 50760.49239507632, 56164.0, 58259.70870145707, 58884.705576471846, 58979.70510147422, 1197.9460924258428, 1352.6753579140936, 1363.4682473835212, 1365.3924003818206, 1379.5792283353474, 21175.576488469836, 29152.562711559767, 29826.85086574553, 30078.24804379908, 30718.232044199074, 38645.2270954585, 47540.81147971328, 47612.761936190094, 47886.0, 47981.0, 519939.0, 570856.1457192854, 594693.0, 594729.0, 595936.0, 25745.356366090997, 26021.0, 26024.959001639723, 26202.0, 26232.213033609023, 46755.0, 47784.0, 48186.5181348182, 48351.516484834705, 48508.2723759151, 40063.79968100141, 40118.997025074605, 40253.0, 40769.1846163081, 41309.38035929524, 30477.847610761804, 37907.62092379041, 37914.0, 38578.228435431694, 39270.607293926696, 37657.05857353588, 38721.2255754889, 38989.8050509757, 39127.217455650156, 39951.400728988505, 30674.233144171576, 34668.740310946865, 34702.0, 34898.47652285269, 35144.0, 24194.395140121636, 25091.498170036863, 25970.87014564915, 26090.217293481226, 26348.20955371342], "value": 18737.935894030983, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.2479054704769, 378.6213786213786, 385.649059355986, 388.10154492350205, 412.98761037168896, 16510.587235319123, 19762.70355944662, 21124.683129753026, 21723.782762172374, 21910.671339929893, 20296.695549566768, 20391.59216815663, 20499.282525111623, 20668.68996965043, 20775.0, 5065.189569668853, 5876.265466816659, 5907.793227237047, 6070.332263451017, 6113.6943152842405, 6038.758449662008, 6052.606580572271, 6091.14723938649, 6091.939080609203, 6092.11664308675, 5811.430913653311, 6017.187679663233, 6041.214642096544, 6084.239470066242, 6088.086786981966, 5791.276090488689, 6012.699365031731, 6041.879162416749, 6066.636001839882, 6083.513318934474, 5148.407933087706, 5160.426070048634, 5266.7629956652045, 5279.52484276417, 5286.180642000496, 23714.762852371427, 24667.62998555023, 24923.750762492506, 25180.622290665655, 25232.873835630704, 2069.1619893942975, 2141.682865038006, 2179.52050548879, 2274.613315736329, 2277.7380601230907, 9936.652217172365, 10136.189104871593, 10344.017318354745, 10431.634892778793, 10648.680539583747, 1935.9516012099668, 1951.2975328881591, 1961.9509512262166, 1996.8302694271003, 2002.4793553676004, 104364.0, 111698.0, 112047.83736894467, 112062.0, 112198.31702524469, 11346.262492937933, 11428.657140285804, 11457.885421145682, 11527.827082593767, 11710.590129345432, 14304.642383940276, 14514.491992780413, 14536.709265814625, 14554.12675239489, 14603.415863365559, 8490.702825401082, 8495.830083398421, 8534.74395768128, 8594.0, 8612.0, 8380.53907035119, 8438.113998030241, 8454.577271136419, 8595.656173753105, 8613.61238744258, 3789.9621003789607, 3813.7139714521522, 4434.933475997863, 4496.662750293741, 4539.818407263672, 1897.2411035585876, 1951.5218771401098, 1953.4823271832975, 1972.8914909680104, 1973.2600274896913, 774.9573773442402, 807.7011505742913, 829.3365307753772, 853.863381858912, 880.8854848869606, 17997.730034049237, 20524.0, 21146.259880904403, 21377.09768018322, 21458.78541214599, 902.5126431726794, 923.4782347973373, 946.9526523673788, 948.0519480519481, 952.2619969523536, 7087.1849737279845, 8298.0, 9516.00081991381, 11332.830007549894, 11360.829587556193, 546258.0, 559284.0, 566216.0, 566735.0, 572450.0, 78745.42509149785, 80659.38681226343, 109800.0, 118284.40857795656, 121200.85546960765, 847.8680960917121, 962.8844538655266, 1043.786023865121, 1048.2190767877828, 1049.742813010802, 19331.80668193356, 19524.414267571996, 19548.511287217934, 20090.698639520127, 20316.984150792698, 46264.768676156404, 52261.216081758044, 53809.46190538201, 57125.0, 58541.4145858536, 52178.73910630422, 74718.0, 77635.61182194053, 79648.60175699084, 80676.0, 8150.714724984834, 8190.672373105247, 8198.877016844872, 8399.202075802788, 8427.705030323908, 10492.580296788043, 12838.614841554769, 12861.356932153541, 13059.869401305865, 13267.734645307233, 10933.398663073607, 11281.661550153509, 11401.315921044765, 11683.88316116828, 11919.225250358699, 10357.274990750575, 10805.783884322112, 10965.56137754481, 11202.83195752048, 11459.31244125355, 11449.713757156138, 11709.126539753599, 11763.294202347886, 11777.823332649845, 11819.468123934272, 8258.174182581934, 8261.504309741435, 8274.627641756257, 8322.417430779788, 8412.663493460193, 8762.912370876209, 10980.396078215776, 10990.615328463466, 11328.207025508136, 11389.658310250707], "value": 9419.024959743532, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.5777038003407, 1418.1987177244855, 1454.3092031285141, 1831.9816801831978, 1877.305397003109, 8439.0, 8712.695055673054, 8911.153440423157, 9534.90465095349, 9573.18627916627, 8893.0, 9186.402883812547, 9225.584848681809, 9348.298877584191, 9352.485613291266, 2467.309153437036, 2492.6136448850457, 2493.650888875559, 2500.562401579716, 2514.396544829237, 2410.795082417988, 2478.6529885816, 2481.62775583663, 2481.9379515512164, 2487.639292302615, 2438.6220135878966, 2471.950560988779, 2503.036331012555, 2504.8622325772076, 2513.5726926422553, 2450.2281781238853, 2453.0065323543904, 2472.55217334839, 2473.332200305925, 2477.352560547234, 2443.8167137464584, 2465.7410971847876, 2467.481828815951, 2490.116008816862, 2491.3397949543387, 9097.63609455621, 9125.5437228139, 9228.0, 9274.211692006187, 9286.860697089549, 5446.2375267462585, 5561.110222364402, 5605.495505404533, 5626.859328516779, 5627.324721033461, 3153.880372467787, 3216.9517457237926, 3281.507773833944, 3478.2000139967695, 3788.488554045196, 970.8883478400004, 1026.743314171457, 1042.1975079188983, 1140.4012893231038, 1818.945431637053, 11992.580259690869, 12112.394380281039, 12231.204971676812, 12254.754904901942, 12297.385130743427, 4455.732656040648, 4721.102990431807, 4792.017636384533, 4795.232762757942, 4815.614750819926, 8588.699395521251, 9080.81838363229, 9441.669541566149, 9527.190188833889, 9556.856647150298, 5054.913193663307, 5453.072977593819, 5460.071787796005, 5460.808871689472, 5463.262459567987, 4610.884727881763, 4675.275332323496, 4780.569748722632, 4926.1379258629595, 5054.69671819692, 3809.8857034289017, 3882.126521532689, 3937.074787424953, 3947.0921688011917, 4024.4164596133396, 1181.8345431639577, 1194.4445832687843, 1196.216478206776, 1201.3272567362305, 1204.3978010994504, 892.6875593542338, 897.582624079806, 901.9729408117767, 904.2991681446799, 935.9017303183203, 5998.4601385875485, 6012.30858451275, 6124.969375153185, 6214.223222097238, 6249.6562689052535, 436.80998765537095, 437.94525684289465, 453.272497641281, 453.3064411450486, 458.3125312031952, 3866.7293289469467, 4018.1561872007205, 4042.8382864685673, 4159.1265834175165, 4190.350495673176, 10965.396903170242, 11116.944415277872, 11244.66266012021, 11353.26203796767, 11358.034567061726, 10777.083947864363, 11215.271007384494, 11312.264702794291, 11313.71715707114, 11325.037371823488, 8355.373346999, 8657.783555411039, 8681.262092722063, 8686.34852386061, 8710.869336959951, 5990.31111422192, 6089.634621922699, 6110.236220472441, 6125.1424800527075, 6171.59884607499, 9871.703848884545, 10426.791464170827, 10495.947520262349, 10512.369257844553, 10573.841392379274, 10460.69241344832, 10586.417747023988, 10591.576336946744, 10602.363858168535, 10643.988821061987, 7188.6046267455795, 7393.704251829867, 7662.885056724265, 7714.344280735976, 7884.645190966535, 8533.487990720576, 8554.615042322986, 8658.960924689078, 8738.737837864874, 8762.123787621442, 7681.42389320792, 7864.567448790372, 7881.960590197242, 7950.4832185907735, 7962.0, 7585.469017168745, 7692.34615057704, 7701.037370328709, 7736.497127686683, 7744.6902123914415, 7186.389156921511, 7306.232845551141, 7310.342069213796, 7310.926890731024, 7323.597202153933, 4464.24107901651, 4989.326440930428, 5095.617828662939, 5101.61261315276, 5127.359080114986, 5013.323201367769, 5018.146915024382, 5144.897102057863, 5146.073706732824, 5182.222666600041], "value": 4625.636396441002, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.35265237696086, 308.2647884794764, 316.40673736743605, 319.69948248646284, 322.242729585474, 5125.719539494727, 5398.568114550836, 5759.4816466518005, 5865.882682346356, 5866.735996880146, 4504.301833215849, 4796.544328288807, 5045.64680472367, 5620.550355971523, 5734.483896449318, 3593.3531964246426, 3954.2684603348403, 4035.354343305072, 4343.6959412841115, 4345.782710864444, 3282.097423208613, 3625.4380571011534, 4279.03721662625, 4303.720258183223, 4346.979238978968, 3631.963680363189, 4228.5560016198315, 4236.809343579531, 4268.807903644328, 4272.188284226003, 3311.784733992295, 4250.022494826192, 4269.594388533084, 4310.245707001262, 4328.805203765838, 3122.188231059918, 3694.2980833641523, 3700.4634328022557, 3745.8013435700586, 3769.492203118747, 4589.770511474413, 4882.679777751163, 5343.706096164709, 5397.325334333208, 5564.10974244123, 4930.827421040246, 4958.727240021604, 4964.826231081895, 4971.37857767779, 4986.4265609455015, 5209.7134657593815, 5666.943330566683, 5993.640381577119, 6026.517878569748, 6029.728662210211, 3959.229128899669, 4053.8783836484954, 4081.3061779497557, 4085.5710150434074, 4109.4246805447265, 5077.746112694351, 6220.720067596969, 6227.657478838662, 6258.71835767387, 6286.057091436275, 3488.337215928965, 3615.2769446110883, 4158.731586865974, 4207.558206388285, 4215.494140703136, 3116.1119081062243, 3915.686745060388, 4134.0, 4185.121124563876, 4223.5428777072475, 3885.4754608128105, 3907.843686252575, 4469.122968353299, 4483.036147228361, 4837.927431088537, 5777.62445441045, 5971.98476259037, 6088.969555152196, 6099.346784148909, 6148.323684394713, 1224.8591411987557, 1293.792993121096, 1350.8986825987893, 1405.5713007532597, 1418.5177039806515, 717.4009701898973, 824.6228797907509, 851.7572491839796, 865.8831057807115, 870.3463419502995, 4235.512916014636, 4349.652027837766, 4409.971413149923, 4693.573691205371, 4748.646635708807, 4678.0, 4801.591864691471, 5139.306193663882, 5159.7678104485385, 5165.405978312542, 4182.456280683553, 4470.764617691155, 4645.606318104555, 4685.750839597013, 4755.762211889391, 2282.463621049052, 2456.36134605004, 2596.7533084356955, 2686.9328266793486, 2698.8110832241555, 4749.667523273407, 5065.189569668835, 5196.60955960255, 5245.7771493946975, 5449.4005659377435, 4683.360823711673, 4720.409948756405, 4844.996102416524, 5055.393352797688, 5192.9740351298005, 4720.159137936139, 4727.290906364073, 4833.564979151894, 5296.947030529645, 5328.6802791832615, 4377.781110944504, 4413.955860441483, 4703.506131856106, 4718.363020992122, 5164.32863727713, 4652.32541281519, 4725.36207611968, 5099.949000510096, 5126.231065340229, 5135.35808023997, 4258.318669012943, 4544.863654090383, 5070.923936140887, 5310.946890531045, 5347.919781203363, 3484.0593039878586, 3513.8418771154847, 3589.5513060867393, 3853.9016380331473, 3983.581723918947, 5912.970435147969, 6029.397060293935, 6126.908096378469, 6174.506039516918, 6186.659204029774, 5308.302152594904, 5847.5906686533235, 5992.910106348496, 6010.87978240424, 6106.572539922163, 4903.779329930233, 5147.176451767699, 5558.388577256579, 5598.552115830642, 5884.499817515559, 4987.351644286219, 5188.6250143711195, 5402.324709411324, 5814.592978491465, 5910.290765108214, 4646.767661616973, 5523.8066667666435, 5959.255093113361, 6020.8259389418645, 6129.754809807558, 4687.67186296956, 5104.417630534546, 5227.372715274039, 5265.578293860713, 5924.555658325557], "value": 4027.6607937668055, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.4494184419846, 737.760227925924, 738.4830618567001, 773.1379511844292, 845.636376358166, 15507.689846203084, 16502.17489125545, 16700.91649541752, 16704.665906681857, 16721.916390418046, 15140.394384224644, 15239.92380038101, 15258.618534536643, 15266.313015914282, 15267.312970916308, 710.6020628448073, 732.4726197138052, 867.4665080975218, 900.4372267332917, 902.8284625921054, 845.3955421873359, 892.0276898180981, 892.7277180459957, 893.566620189211, 898.2679116520048, 909.4770506958492, 909.6816114359954, 946.0964778636386, 947.5309721687775, 947.6730527967875, 905.5155491811882, 930.1349744737391, 940.7554035950702, 941.9670311539131, 943.2878176976408, 690.0752990992045, 702.3152426384286, 706.2584286499207, 707.7275249029161, 708.649218636773, 15060.54818355451, 15363.385464581403, 15369.692606147926, 15395.846041539553, 15444.5366639001, 14902.850971490254, 14906.478273260493, 14931.701365972729, 14959.326830292663, 14970.401183952628, 12053.819192712117, 12123.696907577292, 12153.0, 12284.938575307156, 12373.319467429288, 3969.3053715599654, 4040.5757395473347, 4071.0229544909303, 4188.078622703, 4199.349100889367, 117345.0, 117559.0, 118282.81717182804, 120756.39621801923, 121997.78002220042, 8672.56637168139, 9625.51872406377, 11277.71805704864, 11592.362420066976, 11838.763224735458, 23274.767252327598, 23485.647715284285, 23515.294541163792, 23577.642235776628, 23669.881650591637, 8148.877766833502, 8194.385421093442, 8204.384671149688, 8225.424220304521, 8232.794180145544, 4337.4795024596615, 4453.398791163217, 4470.843520476833, 4517.006258623098, 4620.052889157714, 2934.60382848317, 3598.7660802047785, 3615.746897717187, 3659.8902032939054, 3660.762050466711, 615.187951903483, 654.751194546079, 655.6951017776661, 663.7444583835162, 669.6450881033061, 534.6604905884791, 671.1442910289322, 674.4151244575208, 683.3883674111643, 693.5116364685639, 7550.6602202901, 9054.773630659154, 9759.756006099904, 10006.599736010623, 10749.946250268698, 607.5989846701156, 654.4404534123352, 700.3381804195088, 700.770148389573, 714.7927101140715, 3943.0, 4646.442426908792, 4716.702906700685, 4733.5266473352385, 4767.73777442237, 129512.70487295194, 154696.0, 154942.0, 158698.0, 158933.4373296929, 81774.0, 126678.0, 128501.35749321194, 128790.0, 130355.34822325828, 94542.0, 95978.52010740041, 96650.99999999859, 111550.0, 120048.99877502957, 9746.951265243628, 10109.39343639384, 10141.326681097536, 10156.949215254172, 10433.478326083818, 50934.0, 58197.4180258192, 59347.81304373975, 59825.40174598373, 61436.69281653563, 74007.1498212549, 83164.58417707872, 88691.0, 92360.996535225, 95478.52260738652, 12499.687507812378, 13173.93413032967, 13280.53518126861, 13364.264965426606, 13385.063045586716, 14878.0, 14921.701565968837, 14956.700865982837, 15248.771268431205, 15314.234288285765, 13979.301034948416, 14123.64690882695, 14161.433542658178, 14205.28973551339, 14764.557063288119, 11594.362310073027, 14107.929460352632, 14235.932305076953, 14758.852411476177, 14797.482088126864, 13667.111637743516, 13717.245551494365, 13743.635819107756, 13829.170249784642, 14111.0, 10855.565777369133, 11141.108711303239, 11172.832407514057, 11391.943040284745, 11662.300261984308, 10127.79744405093, 11334.119250493935, 11677.88322116768, 11678.824817627572, 11743.588974385855], "value": 7984.3012943489575, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 137.0, 171.0, 149.0, 159.0, 172.0, 174.0, 117.0, 121.0], "value": 142.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 110.0, 114.0, 182.0, 134.0, 112.0, 111.0, 110.0, 158.0, 140.0], "value": 131.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [215.0, 223.0, 278.0, 282.0, 198.0, 195.0, 197.0, 197.0, 195.0, 198.0], "value": 217.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 82.0, 83.0, 109.0, 73.0, 59.0, 61.0, 60.0, 77.0, 60.0], "value": 76.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 86.0, 72.0, 68.0, 67.0, 89.0, 70.0, 73.0, 87.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 90.0, 88.0, 103.0, 87.0, 92.0, 88.0, 89.0, 88.0], "value": 90.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 95.0, 160.0, 97.0, 96.0, 105.0, 101.0, 149.0, 149.0], "value": 114.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 91.0, 90.0, 90.0, 93.0, 90.0, 90.0, 91.0, 91.0, 90.0], "value": 91.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 54.0, 55.0, 56.0, 57.0, 56.0, 57.0, 55.0, 66.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 71.0, 82.0, 75.0, 68.0, 66.0, 93.0, 105.0, 70.0], "value": 77.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 89.0, 89.0, 75.0, 109.0, 107.0, 106.0, 87.0, 94.0], "value": 93.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 140.0, 145.0, 156.0, 132.0, 155.0, 158.0, 154.0, 173.0, 168.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 168.0, 168.0, 169.0, 170.0, 178.0, 153.0, 214.0, 236.0], "value": 179.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 64.0, 73.0, 70.0, 68.0, 67.0, 64.0, 58.0, 66.0], "value": 66.6, "unit": "score"}]}]} 12:11:29 INFO - Return code: 0 12:11:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:11:29 ERROR - # TBPL SUCCESS # 12:11:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:11:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:11:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:11:29 INFO - 2016-09-26 12:11:29.170 screenresolution[3104:25168] starting screenresolution argv=screenresolution get 12:11:29 INFO - 2016-09-26 12:11:29.173 screenresolution[3104:25168] Display 0: 1600x1200x32@60 12:11:29 INFO - 2016-09-26 12:11:29.179 screenresolution[3105:25171] starting screenresolution argv=screenresolution list 12:11:29 INFO - Available Modes on Display 0 12:11:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:11:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:11:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:11:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:11:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:11:29 INFO - Intel Iris: 12:11:29 INFO - Chipset Model: Intel Iris 12:11:29 INFO - Type: GPU 12:11:29 INFO - Bus: Built-In 12:11:29 INFO - VRAM (Dynamic, Max): 1536 MB 12:11:29 INFO - Vendor: Intel (0x8086) 12:11:29 INFO - Device ID: 0x0a2e 12:11:29 INFO - Revision ID: 0x0009 12:11:29 INFO - Displays: 12:11:29 INFO - Display: 12:11:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:11:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:11:29 INFO - Display Serial Number: 1600x1200 60 12:11:29 INFO - Main Display: Yes 12:11:29 INFO - Mirror: Off 12:11:29 INFO - Online: Yes 12:11:29 INFO - Rotation: Supported 12:11:29 INFO - Return code: 0 12:11:29 INFO - Running post-action listener: _resource_record_post_action 12:11:29 INFO - [mozharness: 2016-09-26 19:11:29.415069Z] Finished run-tests step (success) 12:11:29 INFO - Running post-run listener: _resource_record_post_run 12:11:29 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 765952; Write bytes: 542210560; Read time: 7; Write time: 1701 12:11:29 INFO - TinderboxPrint: CPU usage
54.4% 12:11:29 INFO - TinderboxPrint: I/O read bytes / time
765,952 / 7 12:11:29 INFO - TinderboxPrint: I/O write bytes / time
542,210,560 / 1,701 12:11:29 INFO - TinderboxPrint: CPU idle
2,188.3 (45.6%) 12:11:29 INFO - TinderboxPrint: CPU system
968.8 (20.2%) 12:11:29 INFO - TinderboxPrint: CPU user
1,644.6 (34.3%) 12:11:29 INFO - TinderboxPrint: Swap in / out
241,147,904 / 0 12:11:29 INFO - install - Wall time: 25s; CPU: 54.0%; Read bytes: 228921856; Write bytes: 222418432; Read time: 19578; Write time: 495 12:11:29 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 765952; Write bytes: 298828800; Read time: 7; Write time: 1153 12:11:29 INFO - Running post-run listener: _upload_blobber_files 12:11:29 INFO - Blob upload gear active. 12:11:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:11:29 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 12:11:29 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', 'Date', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:11:29 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 Date -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:11:30 INFO - (blobuploader) - INFO - Open directory for files ... 12:11:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:11:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:11:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:11:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:11:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:11:30 INFO - (blobuploader) - INFO - Done attempting. 12:11:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:11:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:11:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:11:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:11:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:11:31 INFO - (blobuploader) - INFO - Done attempting. 12:11:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:11:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:11:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:11:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:11:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:11:34 INFO - (blobuploader) - INFO - Done attempting. 12:11:34 INFO - (blobuploader) - INFO - Iteration through files over. 12:11:34 INFO - Return code: 0 12:11:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:11:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:11:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date/sha512/1519acda69baf42481eee15620219d0e4984a2de40e2d426888bc727e1c517943546d49ff57d616970f5f44273c9b19b90bc710f3c9e282e8064251ea012065b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date/sha512/b4794983d4a77d295c72890cb8ff17af5138aa280855212525032d4e1486e57c2eece15c9b05d4c1b9c96a2f7c50cb580e82d3f1f75824ea0c31fdd40a662cf6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date/sha512/66b09cef358e023d2501b84ae60513ede1b35c7be2948343f49be3d829d30675e4de340c9d600165cb11bb41bdbcf6326a059206536738044fb950206e755505"} 12:11:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:11:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:11:34 INFO - Contents: 12:11:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date/sha512/1519acda69baf42481eee15620219d0e4984a2de40e2d426888bc727e1c517943546d49ff57d616970f5f44273c9b19b90bc710f3c9e282e8064251ea012065b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date/sha512/b4794983d4a77d295c72890cb8ff17af5138aa280855212525032d4e1486e57c2eece15c9b05d4c1b9c96a2f7c50cb580e82d3f1f75824ea0c31fdd40a662cf6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date/sha512/66b09cef358e023d2501b84ae60513ede1b35c7be2948343f49be3d829d30675e4de340c9d600165cb11bb41bdbcf6326a059206536738044fb950206e755505"} 12:11:34 INFO - Running post-run listener: copy_logs_to_upload_dir 12:11:34 INFO - Copying logs to upload dir... 12:11:34 INFO - mkdir: /builds/slave/test/build/upload/logs 12:11:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.787145 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-09-26 12:11:34.738117) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-26 12:11:34.741998) ========= 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.2cMVMgW5ub/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.2UvYiZj37l/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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/Date/sha512/1519acda69baf42481eee15620219d0e4984a2de40e2d426888bc727e1c517943546d49ff57d616970f5f44273c9b19b90bc710f3c9e282e8064251ea012065b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date/sha512/b4794983d4a77d295c72890cb8ff17af5138aa280855212525032d4e1486e57c2eece15c9b05d4c1b9c96a2f7c50cb580e82d3f1f75824ea0c31fdd40a662cf6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date/sha512/66b09cef358e023d2501b84ae60513ede1b35c7be2948343f49be3d829d30675e4de340c9d600165cb11bb41bdbcf6326a059206536738044fb950206e755505"} build_url:https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010935 build_url: 'https://queue.taskcluster.net/v1/task/SvJsOl5ZQPOZLHSfLMETHw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date/sha512/1519acda69baf42481eee15620219d0e4984a2de40e2d426888bc727e1c517943546d49ff57d616970f5f44273c9b19b90bc710f3c9e282e8064251ea012065b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date/sha512/b4794983d4a77d295c72890cb8ff17af5138aa280855212525032d4e1486e57c2eece15c9b05d4c1b9c96a2f7c50cb580e82d3f1f75824ea0c31fdd40a662cf6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date/sha512/66b09cef358e023d2501b84ae60513ede1b35c7be2948343f49be3d829d30675e4de340c9d600165cb11bb41bdbcf6326a059206536738044fb950206e755505"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-26 12:11:34.778432) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 12:11:34.778810) ========= 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.2cMVMgW5ub/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.2UvYiZj37l/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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.005200 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 12:11:34.827448) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-26 12:11:34.827782) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-26 12:11:34.828121) ========= ========= Total master_lag: 0.80 =========