builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0117 starttime: 1481905473.61 results: success (0) buildid: 20161216070349 builduid: d6e0a216762947fa8a4c718bec1bff70 revision: 917fc78005ddd0f15013e8a9e0b473468a8b2f14 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 08:24:33.614686) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 08:24:33.615098) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 08:24:33.615374) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 08:24:33.643457) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:24:33.643813) ========= 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.mBwJ9nndGN/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.u3ZZfBoKlW/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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-12-16 08:24:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 63.5M=0s 2016-12-16 08:24:33 (63.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123818 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:24:33.791650) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:24:33.795018) ========= 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.mBwJ9nndGN/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.u3ZZfBoKlW/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.026225 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:24:33.843442) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:24:33.843785) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 917fc78005ddd0f15013e8a9e0b473468a8b2f14 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 917fc78005ddd0f15013e8a9e0b473468a8b2f14 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mBwJ9nndGN/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.u3ZZfBoKlW/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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-12-16 08:24:33,939 truncating revision to first 12 chars 2016-12-16 08:24:33,939 Setting DEBUG logging. 2016-12-16 08:24:33,939 attempt 1/10 2016-12-16 08:24:33,939 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/917fc78005dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-16 08:24:34,581 unpacking tar archive at: mozilla-central-917fc78005dd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.918759 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:24:34.788926) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 08:24:34.789439) ========= script_repo_revision: 917fc78005ddd0f15013e8a9e0b473468a8b2f14 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 08:24:34.790056) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 08:24:34.790506) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 08:24:34.811374) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-12-16 08:24:34.811715) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mBwJ9nndGN/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.u3ZZfBoKlW/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 08:24:35 INFO - MultiFileLogger online at 20161216 08:24:35 in /builds/slave/test 08:24:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:24:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:24:35 INFO - {'append_to_log': False, 08:24:35 INFO - 'base_work_dir': '/builds/slave/test', 08:24:35 INFO - 'blob_upload_branch': 'Firefox', 08:24:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:24:35 INFO - 'branch': 'Firefox', 08:24:35 INFO - 'buildbot_json_path': 'buildprops.json', 08:24:35 INFO - 'config_files': ('talos/mac_config.py',), 08:24:35 INFO - 'default_actions': ('clobber', 08:24:35 INFO - 'read-buildbot-config', 08:24:35 INFO - 'download-and-extract', 08:24:35 INFO - 'populate-webroot', 08:24:35 INFO - 'create-virtualenv', 08:24:35 INFO - 'install', 08:24:35 INFO - 'run-tests'), 08:24:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:24:35 INFO - 'download_minidump_stackwalk': True, 08:24:35 INFO - 'download_symbols': 'ondemand', 08:24:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:24:35 INFO - 'tooltool.py': '/tools/tooltool.py', 08:24:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:24:35 INFO - '/tools/misc-python/virtualenv.py')}, 08:24:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:24:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:24:35 INFO - 'installer_path': 'installer.exe', 08:24:35 INFO - 'log_level': 'info', 08:24:35 INFO - 'log_name': 'talos', 08:24:35 INFO - 'log_to_console': True, 08:24:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:24:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:24:35 INFO - 'opt_config_files': (), 08:24:35 INFO - 'pip_index': False, 08:24:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:24:35 INFO - 'cmd': ('bash', 08:24:35 INFO - '-c', 08:24:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:24:35 INFO - 'enabled': True, 08:24:35 INFO - 'halt_on_failure': False, 08:24:35 INFO - 'name': 'check_screen_resolution'},), 08:24:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:24:35 INFO - 'cmd': ('bash', 08:24:35 INFO - '-c', 08:24:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:24:35 INFO - 'enabled': True, 08:24:35 INFO - 'halt_on_failure': False, 08:24:35 INFO - 'name': 'check_screen_resolution'},), 08:24:35 INFO - 'run_cmd_checks_enabled': True, 08:24:35 INFO - 'sps_profile': False, 08:24:35 INFO - 'sps_profile_interval': 0, 08:24:35 INFO - 'suite': 'dromaeojs-e10s', 08:24:35 INFO - 'system_bits': '32', 08:24:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:24:35 INFO - 'title': 't-yosemite-r7-0117', 08:24:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:24:35 INFO - 'use_talos_json': True, 08:24:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:24:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:24:35 INFO - 'work_dir': 'build'} 08:24:35 INFO - [mozharness: 2016-12-16 16:24:35.015958Z] Running clobber step. 08:24:35 INFO - Running pre-action listener: _resource_record_pre_action 08:24:35 INFO - Running main action method: clobber 08:24:35 INFO - rmtree: /builds/slave/test/build 08:24:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:24:37 INFO - Running post-action listener: _resource_record_post_action 08:24:37 INFO - [mozharness: 2016-12-16 16:24:37.414119Z] Finished clobber step (success) 08:24:37 INFO - [mozharness: 2016-12-16 16:24:37.414247Z] Running read-buildbot-config step. 08:24:37 INFO - Running pre-action listener: _resource_record_pre_action 08:24:37 INFO - Running main action method: read_buildbot_config 08:24:37 INFO - Using buildbot properties: 08:24:37 INFO - { 08:24:37 INFO - "project": "", 08:24:37 INFO - "product": "firefox", 08:24:37 INFO - "script_repo_revision": "production", 08:24:37 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:24:37 INFO - "repository": "", 08:24:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:24:37 INFO - "buildid": "20161216070349", 08:24:37 INFO - "pgo_build": "False", 08:24:37 INFO - "basedir": "/builds/slave/test", 08:24:37 INFO - "buildnumber": 19, 08:24:37 INFO - "slavename": "t-yosemite-r7-0117", 08:24:37 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:24:37 INFO - "platform": "yosemite_r7", 08:24:37 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:24:37 INFO - "branch": "mozilla-central", 08:24:37 INFO - "repo_path": "mozilla-central", 08:24:37 INFO - "revision": "917fc78005ddd0f15013e8a9e0b473468a8b2f14", 08:24:37 INFO - "stage_platform": "macosx64", 08:24:37 INFO - "builduid": "d6e0a216762947fa8a4c718bec1bff70", 08:24:37 INFO - "slavebuilddir": "test" 08:24:37 INFO - } 08:24:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:24:37 INFO - Running post-action listener: _resource_record_post_action 08:24:37 INFO - [mozharness: 2016-12-16 16:24:37.417519Z] Finished read-buildbot-config step (success) 08:24:37 INFO - [mozharness: 2016-12-16 16:24:37.417622Z] Running download-and-extract step. 08:24:37 INFO - Running pre-action listener: _resource_record_pre_action 08:24:37 INFO - Running main action method: download_and_extract 08:24:37 INFO - mkdir: /builds/slave/test/build/tests 08:24:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:37 INFO - trying https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:24:37 INFO - Downloading https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:24:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 08:24:38 INFO - Downloaded 1199 bytes. 08:24:38 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:24:38 INFO - Using the following test package requirements: 08:24:38 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:24:38 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:24:38 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:24:38 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:24:38 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:24:38 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:24:38 INFO - u'jsshell-mac.zip'], 08:24:38 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:24:38 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:24:38 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:24:38 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:24:38 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:24:38 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:24:38 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:24:38 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:24:38 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:24:38 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:24:38 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:24:38 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:24:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:24:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:24:38 INFO - Fetch https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:24:39 INFO - Expected file size: 16277482 08:24:39 INFO - Obtained file size: 16277482 08:24:46 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:24:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:24:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:24:46 INFO - Fetch https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:24:47 INFO - Expected file size: 16277482 08:24:47 INFO - Obtained file size: 16277482 08:24:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:24:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:24:53 INFO - Fetch https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:24:55 INFO - Expected file size: 14409734 08:24:55 INFO - Obtained file size: 14409734 08:24:55 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')]} 08:24:55 INFO - trying https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:24:55 INFO - Downloading https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:24:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 08:24:57 INFO - Downloaded 69572500 bytes. 08:24:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:24:57 INFO - mkdir: /builds/slave/test/properties 08:24:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:24:57 INFO - Writing to file /builds/slave/test/properties/build_url 08:24:57 INFO - Contents: 08:24:57 INFO - build_url:https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:24:58 INFO - Running post-action listener: _resource_record_post_action 08:24:58 INFO - Running post-action listener: set_extra_try_arguments 08:24:58 INFO - [mozharness: 2016-12-16 16:24:58.444821Z] Finished download-and-extract step (success) 08:24:58 INFO - [mozharness: 2016-12-16 16:24:58.444980Z] Running populate-webroot step. 08:24:58 INFO - Running pre-action listener: _resource_record_pre_action 08:24:58 INFO - Running main action method: populate_webroot 08:24:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:24:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:24:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:24:58 INFO - '--tppagecycles', 08:24:58 INFO - '7'], 08:24:58 INFO - 'tests': ['tsvgm']}}, 08:24:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:24:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:24:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:24:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:24:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:24:58 INFO - 'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:24:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:24:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:24:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:24:58 INFO - 'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['damp', 'tps']}, 08:24:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:24:58 INFO - 'tests': ['damp', 'tps']}, 08:24:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['dromaeo_dom']}, 08:24:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:24:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['basic_compositor_video']}, 08:24:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:24:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['a11yr', 08:24:58 INFO - 'ts_paint', 08:24:58 INFO - 'tpaint', 08:24:58 INFO - 'sessionrestore', 08:24:58 INFO - 'sessionrestore_no_auto_restore', 08:24:58 INFO - 'tabpaint']}, 08:24:58 INFO - 'other-e10s': {'tests': ['a11yr', 08:24:58 INFO - 'ts_paint', 08:24:58 INFO - 'tpaint', 08:24:58 INFO - 'sessionrestore', 08:24:58 INFO - 'sessionrestore_no_auto_restore', 08:24:58 INFO - 'tabpaint']}, 08:24:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:24:58 INFO - 'ts_paint', 08:24:58 INFO - 'tpaint', 08:24:58 INFO - 'sessionrestore', 08:24:58 INFO - 'sessionrestore_no_auto_restore', 08:24:58 INFO - 'tabpaint']}, 08:24:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:24:58 INFO - 'ts_paint', 08:24:58 INFO - 'tpaint', 08:24:58 INFO - 'sessionrestore', 08:24:58 INFO - 'sessionrestore_no_auto_restore', 08:24:58 INFO - 'tabpaint']}, 08:24:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['a11yr', 08:24:58 INFO - 'ts_paint', 08:24:58 INFO - 'tpaint', 08:24:58 INFO - 'sessionrestore', 08:24:58 INFO - 'sessionrestore_no_auto_restore', 08:24:58 INFO - 'tabpaint']}, 08:24:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['a11yr', 08:24:58 INFO - 'ts_paint', 08:24:58 INFO - 'tpaint', 08:24:58 INFO - 'sessionrestore', 08:24:58 INFO - 'sessionrestore_no_auto_restore', 08:24:58 INFO - 'tabpaint']}, 08:24:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['tsvgx', 08:24:58 INFO - 'tsvgr_opacity', 08:24:58 INFO - 'tart', 08:24:58 INFO - 'tscrollx', 08:24:58 INFO - 'cart', 08:24:58 INFO - 'tsvg_static']}, 08:24:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:24:58 INFO - 'tsvgr_opacity', 08:24:58 INFO - 'tart', 08:24:58 INFO - 'tscrollx', 08:24:58 INFO - 'cart', 08:24:58 INFO - 'tsvg_static']}, 08:24:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:24:58 INFO - 'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['tp5o']}, 08:24:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:24:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:24:58 INFO - 'talos_options': ['--disable-e10s', 08:24:58 INFO - '--xperf_path', 08:24:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:24:58 INFO - 'tests': ['tp5n']}, 08:24:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:24:58 INFO - 'talos_options': ['--xperf_path', 08:24:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:24:58 INFO - 'tests': ['tp5n']}}, 08:24:58 INFO - 'talos.zip': {'path': '', 08:24:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:24:58 INFO - Running post-action listener: _resource_record_post_action 08:24:58 INFO - [mozharness: 2016-12-16 16:24:58.463636Z] Finished populate-webroot step (success) 08:24:58 INFO - [mozharness: 2016-12-16 16:24:58.463746Z] Running create-virtualenv step. 08:24:58 INFO - Running pre-action listener: _resource_record_pre_action 08:24:58 INFO - Running main action method: create_virtualenv 08:24:58 INFO - Creating virtualenv /builds/slave/test/build/venv 08:24: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 08:24:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:24:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:24:58 INFO - Using real prefix '/tools/python27' 08:24:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:24:59 INFO - Installing distribute.............................................................................................................................................................................................done. 08:25:02 INFO - Installing pip.................done. 08:25:02 INFO - Return code: 0 08:25:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:25:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:25:02 INFO - Reading from file tmpfile_stdout 08:25:02 INFO - Output received: 08:25:02 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) 08:25:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:25: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')]} 08:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25: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')]} 08:25:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c0343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf73c59d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be49d30>, '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 0x10bcb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be41b20>, '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.mBwJ9nndGN/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.u3ZZfBoKlW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 08:25: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', 'pip>=1.5'] in /builds/slave/test/build 08:25: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 pip>=1.5 08:25:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBwJ9nndGN/Render', 08:25:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:02 INFO - 'HOME': '/Users/cltbld', 08:25:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:02 INFO - 'LOGNAME': 'cltbld', 08:25:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:02 INFO - 'MOZ_NO_REMOTE': '1', 08:25:02 INFO - 'NO_EM_RESTART': '1', 08:25:02 INFO - 'PAGER': '/bin/cat', 08:25:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:02 INFO - 'PWD': '/builds/slave/test', 08:25:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:02 INFO - 'SHELL': '/bin/bash', 08:25:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3ZZfBoKlW/Listeners', 08:25:02 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 08:25:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:02 INFO - 'USER': 'cltbld', 08:25:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:02 INFO - 'XPC_FLAGS': '0x0', 08:25:02 INFO - 'XPC_SERVICE_NAME': '0', 08:25:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25: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 08:25:02 INFO - Cleaning up... 08:25:02 INFO - Return code: 0 08:25:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:25: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')]} 08:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25: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')]} 08:25:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25: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 0x10c0343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf73c59d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be49d30>, '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 0x10bcb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be41b20>, '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.mBwJ9nndGN/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.u3ZZfBoKlW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 08:25: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 08:25: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 08:25:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBwJ9nndGN/Render', 08:25:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:02 INFO - 'HOME': '/Users/cltbld', 08:25:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:02 INFO - 'LOGNAME': 'cltbld', 08:25:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:02 INFO - 'MOZ_NO_REMOTE': '1', 08:25:02 INFO - 'NO_EM_RESTART': '1', 08:25:02 INFO - 'PAGER': '/bin/cat', 08:25:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:02 INFO - 'PWD': '/builds/slave/test', 08:25:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:02 INFO - 'SHELL': '/bin/bash', 08:25:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3ZZfBoKlW/Listeners', 08:25:02 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 08:25:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:02 INFO - 'USER': 'cltbld', 08:25:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:02 INFO - 'XPC_FLAGS': '0x0', 08:25:02 INFO - 'XPC_SERVICE_NAME': '0', 08:25:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:03 INFO - Downloading/unpacking psutil>=3.1.1 08:25:03 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 08:25:03 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 08:25:03 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 08:25:03 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 08:25:03 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 08:25:03 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 08:25:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:25:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:25:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:25:06 INFO - Installing collected packages: psutil 08:25:06 INFO - Running setup.py install for psutil 08:25:06 INFO - building 'psutil._psutil_osx' extension 08:25:06 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 08:25:07 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 08:25:07 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 08:25:07 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 08:25:07 INFO - building 'psutil._psutil_posix' extension 08:25:07 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 08:25:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:25:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:25:07 INFO - ^ 08:25:07 INFO - 1 warning generated. 08:25:07 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 08:25:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:25:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:25:07 INFO - Successfully installed psutil 08:25:07 INFO - Cleaning up... 08:25:07 INFO - Return code: 0 08:25:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:25:07 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')]} 08:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:07 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')]} 08:25:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:07 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 0x10c0343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf73c59d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be49d30>, '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 0x10bcb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be41b20>, '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.mBwJ9nndGN/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.u3ZZfBoKlW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 08:25:07 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 08:25:07 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 08:25:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBwJ9nndGN/Render', 08:25:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:07 INFO - 'HOME': '/Users/cltbld', 08:25:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:07 INFO - 'LOGNAME': 'cltbld', 08:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:07 INFO - 'MOZ_NO_REMOTE': '1', 08:25:07 INFO - 'NO_EM_RESTART': '1', 08:25:07 INFO - 'PAGER': '/bin/cat', 08:25:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:07 INFO - 'PWD': '/builds/slave/test', 08:25:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:07 INFO - 'SHELL': '/bin/bash', 08:25:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3ZZfBoKlW/Listeners', 08:25:07 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 08:25:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:07 INFO - 'USER': 'cltbld', 08:25:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:07 INFO - 'XPC_FLAGS': '0x0', 08:25:07 INFO - 'XPC_SERVICE_NAME': '0', 08:25:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:25:08 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 08:25:08 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 08:25:08 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 08:25:08 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 08:25:08 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 08:25:08 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 08:25:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:25:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:25:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:25:10 INFO - Installing collected packages: mozsystemmonitor 08:25:10 INFO - Running setup.py install for mozsystemmonitor 08:25:10 INFO - Successfully installed mozsystemmonitor 08:25:10 INFO - Cleaning up... 08:25:10 INFO - Return code: 0 08:25:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:25:10 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')]} 08:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:10 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')]} 08:25:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:10 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 0x10c0343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf73c59d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be49d30>, '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 0x10bcb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be41b20>, '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.mBwJ9nndGN/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.u3ZZfBoKlW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 08:25:10 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 08:25:10 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 08:25:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBwJ9nndGN/Render', 08:25:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:10 INFO - 'HOME': '/Users/cltbld', 08:25:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:10 INFO - 'LOGNAME': 'cltbld', 08:25:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:10 INFO - 'MOZ_NO_REMOTE': '1', 08:25:10 INFO - 'NO_EM_RESTART': '1', 08:25:10 INFO - 'PAGER': '/bin/cat', 08:25:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:10 INFO - 'PWD': '/builds/slave/test', 08:25:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:10 INFO - 'SHELL': '/bin/bash', 08:25:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3ZZfBoKlW/Listeners', 08:25:10 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 08:25:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:10 INFO - 'USER': 'cltbld', 08:25:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:10 INFO - 'XPC_FLAGS': '0x0', 08:25:10 INFO - 'XPC_SERVICE_NAME': '0', 08:25:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:11 INFO - Downloading/unpacking jsonschema==2.5.1 08:25: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 08:25: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 08:25: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 08:25: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 08:25: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 08:25: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 08:25:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:25:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:25: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 08:25: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 08:25: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 08:25: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 08:25: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 08:25: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 08:25:14 INFO - Downloading functools32-3.2.3-2.tar.gz 08:25:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:25:14 INFO - warning: no files found matching '*.txt' 08:25:14 INFO - no previously-included directories found matching 'build' 08:25:14 INFO - no previously-included directories found matching 'dist' 08:25:14 INFO - no previously-included directories found matching '.git*' 08:25:14 INFO - Installing collected packages: jsonschema, functools32 08:25:14 INFO - Running setup.py install for functools32 08:25:14 INFO - warning: no files found matching '*.txt' 08:25:14 INFO - no previously-included directories found matching 'build' 08:25:14 INFO - no previously-included directories found matching 'dist' 08:25:14 INFO - no previously-included directories found matching '.git*' 08:25:14 INFO - Successfully installed jsonschema functools32 08:25:14 INFO - Cleaning up... 08:25:14 INFO - Return code: 0 08:25:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:25:14 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')]} 08:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:14 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')]} 08:25:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:14 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 0x10c0343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf73c59d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be49d30>, '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 0x10bcb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be41b20>, '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.mBwJ9nndGN/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.u3ZZfBoKlW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 08:25:14 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 08:25:14 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 08:25:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBwJ9nndGN/Render', 08:25:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:14 INFO - 'HOME': '/Users/cltbld', 08:25:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:14 INFO - 'LOGNAME': 'cltbld', 08:25:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:14 INFO - 'MOZ_NO_REMOTE': '1', 08:25:14 INFO - 'NO_EM_RESTART': '1', 08:25:14 INFO - 'PAGER': '/bin/cat', 08:25:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:14 INFO - 'PWD': '/builds/slave/test', 08:25:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:14 INFO - 'SHELL': '/bin/bash', 08:25:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3ZZfBoKlW/Listeners', 08:25:14 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 08:25:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:14 INFO - 'USER': 'cltbld', 08:25:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:14 INFO - 'XPC_FLAGS': '0x0', 08:25:14 INFO - 'XPC_SERVICE_NAME': '0', 08:25:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:25:14 INFO - Cleaning up... 08:25:14 INFO - Return code: 0 08:25:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:25:14 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')]} 08:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:14 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')]} 08:25:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:14 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 0x10c0343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf73c59d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be49d30>, '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 0x10bcb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be41b20>, '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.mBwJ9nndGN/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.u3ZZfBoKlW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 08:25:14 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 08:25:14 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 08:25:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBwJ9nndGN/Render', 08:25:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:14 INFO - 'HOME': '/Users/cltbld', 08:25:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:14 INFO - 'LOGNAME': 'cltbld', 08:25:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:14 INFO - 'MOZ_NO_REMOTE': '1', 08:25:14 INFO - 'NO_EM_RESTART': '1', 08:25:14 INFO - 'PAGER': '/bin/cat', 08:25:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:14 INFO - 'PWD': '/builds/slave/test', 08:25:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:14 INFO - 'SHELL': '/bin/bash', 08:25:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3ZZfBoKlW/Listeners', 08:25:14 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 08:25:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:14 INFO - 'USER': 'cltbld', 08:25:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:14 INFO - 'XPC_FLAGS': '0x0', 08:25:14 INFO - 'XPC_SERVICE_NAME': '0', 08:25:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:14 INFO - Downloading/unpacking blobuploader==1.2.4 08:25:14 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 08:25:14 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 08:25:14 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 08:25:14 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 08:25:14 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 08:25:14 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 08:25:17 INFO - Downloading blobuploader-1.2.4.tar.gz 08:25:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:25:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:25: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 08:25: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 08:25: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 08:25: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 08:25: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 08:25: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 08:25:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:25:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:25:18 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 08:25:18 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 08:25:18 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 08:25:18 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 08:25:18 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 08:25:18 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 08:25:18 INFO - Downloading docopt-0.6.1.tar.gz 08:25:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:25:18 INFO - Installing collected packages: blobuploader, requests, docopt 08:25:18 INFO - Running setup.py install for blobuploader 08:25:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:25:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:25:18 INFO - Running setup.py install for requests 08:25:19 INFO - Running setup.py install for docopt 08:25:19 INFO - Successfully installed blobuploader requests docopt 08:25:19 INFO - Cleaning up... 08:25:19 INFO - Return code: 0 08:25:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:25:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:19 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 0x10c0343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf73c59d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be49d30>, '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 0x10bcb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be41b20>, '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.mBwJ9nndGN/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.u3ZZfBoKlW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 08:25:19 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 08:25:19 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 08:25:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBwJ9nndGN/Render', 08:25:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:19 INFO - 'HOME': '/Users/cltbld', 08:25:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:19 INFO - 'LOGNAME': 'cltbld', 08:25:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:19 INFO - 'MOZ_NO_REMOTE': '1', 08:25:19 INFO - 'NO_EM_RESTART': '1', 08:25:19 INFO - 'PAGER': '/bin/cat', 08:25:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:19 INFO - 'PWD': '/builds/slave/test', 08:25:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:19 INFO - 'SHELL': '/bin/bash', 08:25:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3ZZfBoKlW/Listeners', 08:25:19 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 08:25:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:19 INFO - 'USER': 'cltbld', 08:25:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:19 INFO - 'XPC_FLAGS': '0x0', 08:25:19 INFO - 'XPC_SERVICE_NAME': '0', 08:25:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:25:20 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-ep_vtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:25:20 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Mk96DK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:25:20 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-a2SzQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:25:20 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-3sSFts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:25:20 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-L7QYp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:25:20 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-mrhw5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:25:20 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-cu2Js8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:25:20 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-WJ6cwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:25:20 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Tvyhje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:25:20 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-ouj6iO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:25:21 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-0HIVPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:25:21 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-_9urqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:25:21 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-w5QcwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:25:21 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-F_W7C3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:21 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-CD41ia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:25:21 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Ay7247-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:25:21 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-jIISJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:25:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:25:21 INFO - Running setup.py install for manifestparser 08:25:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:25:21 INFO - Running setup.py install for mozcrash 08:25:22 INFO - Running setup.py install for mozdebug 08:25:22 INFO - Running setup.py install for mozdevice 08:25:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:25:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:25:22 INFO - Running setup.py install for mozfile 08:25:22 INFO - Running setup.py install for mozhttpd 08:25:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:25:22 INFO - Running setup.py install for mozinfo 08:25:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:25:22 INFO - Running setup.py install for mozInstall 08:25:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:25:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:25:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:25:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:25:22 INFO - Running setup.py install for mozleak 08:25:23 INFO - Running setup.py install for mozlog 08:25:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:25:23 INFO - Running setup.py install for moznetwork 08:25:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:25:23 INFO - Running setup.py install for mozprocess 08:25:23 INFO - Running setup.py install for mozprofile 08:25:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:25:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:25:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:25:23 INFO - Running setup.py install for mozrunner 08:25:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:25:23 INFO - Running setup.py install for mozscreenshot 08:25:24 INFO - Running setup.py install for moztest 08:25:24 INFO - Running setup.py install for mozversion 08:25:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:25:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:25:24 INFO - Cleaning up... 08:25:24 INFO - Return code: 0 08:25:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:25:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:24 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 0x10c0343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf73c59d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be49d30>, '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 0x10bcb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be41b20>, '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.mBwJ9nndGN/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.u3ZZfBoKlW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 08:25:24 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 08:25:24 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 08:25:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBwJ9nndGN/Render', 08:25:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:24 INFO - 'HOME': '/Users/cltbld', 08:25:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:24 INFO - 'LOGNAME': 'cltbld', 08:25:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:24 INFO - 'MOZ_NO_REMOTE': '1', 08:25:24 INFO - 'NO_EM_RESTART': '1', 08:25:24 INFO - 'PAGER': '/bin/cat', 08:25:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:24 INFO - 'PWD': '/builds/slave/test', 08:25:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:24 INFO - 'SHELL': '/bin/bash', 08:25:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3ZZfBoKlW/Listeners', 08:25:24 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 08:25:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:24 INFO - 'USER': 'cltbld', 08:25:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:24 INFO - 'XPC_FLAGS': '0x0', 08:25:24 INFO - 'XPC_SERVICE_NAME': '0', 08:25:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:25:24 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-ztFMAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:25:24 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)) 08:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:25:24 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-5CTeol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:25:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:25:24 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-_mUOU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:25:24 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)) 08:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:25:25 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-IdcoWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:25:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 08:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:25:25 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-FOHTTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:25:25 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)) 08:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:25:25 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-IX4PUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:25:25 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)) 08:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:25:25 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-_SlOxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:25:25 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)) 08:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:25:25 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-QZXM2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:25:25 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)) 08:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:25:25 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-SDJvoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:25:25 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)) 08:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:25:25 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-JNwnYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:25:25 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)) 08:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:25:25 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-uxCh1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:25:25 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)) 08:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:25:25 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-6Ld7lR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:25:25 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)) 08:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:25:25 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-22o0Ft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:25:26 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)) 08:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:25:26 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-oswYqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:26 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-yDsYFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:26 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)) 08:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:25:26 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-DhITaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:25:26 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)) 08:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:25:26 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-o1qdb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:25:26 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)) 08:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:25:26 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)) 08:25:26 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:25:26 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:25:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:25:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:29 INFO - Downloading blessings-1.6.tar.gz 08:25:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:25:29 INFO - Installing collected packages: blessings 08:25:29 INFO - Running setup.py install for blessings 08:25:29 INFO - Successfully installed blessings 08:25:29 INFO - Cleaning up... 08:25:29 INFO - Return code: 0 08:25:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:25:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:25:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:25:30 INFO - Reading from file tmpfile_stdout 08:25:30 INFO - Current package versions: 08:25:30 INFO - blessings == 1.6 08:25:30 INFO - blobuploader == 1.2.4 08:25:30 INFO - docopt == 0.6.1 08:25:30 INFO - functools32 == 3.2.3-2 08:25:30 INFO - jsonschema == 2.5.1 08:25:30 INFO - manifestparser == 1.1 08:25:30 INFO - mozInstall == 1.12 08:25:30 INFO - mozcrash == 1.0 08:25:30 INFO - mozdebug == 0.1 08:25:30 INFO - mozdevice == 0.49 08:25:30 INFO - mozfile == 1.2 08:25:30 INFO - mozhttpd == 0.7 08:25:30 INFO - mozinfo == 0.9 08:25:30 INFO - mozleak == 0.1 08:25:30 INFO - mozlog == 3.4 08:25:30 INFO - moznetwork == 0.27 08:25:30 INFO - mozprocess == 0.23 08:25:30 INFO - mozprofile == 0.28 08:25:30 INFO - mozrunner == 6.13 08:25:30 INFO - mozscreenshot == 0.1 08:25:30 INFO - mozsystemmonitor == 0.3 08:25:30 INFO - moztest == 0.7 08:25:30 INFO - mozversion == 1.4 08:25:30 INFO - psutil == 3.1.1 08:25:30 INFO - requests == 1.2.3 08:25:30 INFO - wsgiref == 0.1.2 08:25:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:30 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 0x10c0343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf73c59d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be49d30>, '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 0x10bcb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be41b20>, '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.mBwJ9nndGN/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.u3ZZfBoKlW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 08:25:30 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 08:25:30 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 08:25:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBwJ9nndGN/Render', 08:25:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:30 INFO - 'HOME': '/Users/cltbld', 08:25:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:30 INFO - 'LOGNAME': 'cltbld', 08:25:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:30 INFO - 'MOZ_NO_REMOTE': '1', 08:25:30 INFO - 'NO_EM_RESTART': '1', 08:25:30 INFO - 'PAGER': '/bin/cat', 08:25:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:30 INFO - 'PWD': '/builds/slave/test', 08:25:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:30 INFO - 'SHELL': '/bin/bash', 08:25:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3ZZfBoKlW/Listeners', 08:25:30 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 08:25:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:30 INFO - 'USER': 'cltbld', 08:25:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:30 INFO - 'XPC_FLAGS': '0x0', 08:25:30 INFO - 'XPC_SERVICE_NAME': '0', 08:25:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:30 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)) 08:25:30 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)) 08:25:30 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)) 08:25:30 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)) 08:25:30 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)) 08:25:30 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)) 08:25:30 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)) 08:25:30 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)) 08:25:30 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)) 08:25:30 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)) 08:25:30 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)) 08:25:30 INFO - Cleaning up... 08:25:30 INFO - Return code: 0 08:25:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c0343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfaa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf73c59d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be49d30>, '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 0x10bcb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be41b20>, '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.mBwJ9nndGN/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.u3ZZfBoKlW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 08:25:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:25:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:25:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBwJ9nndGN/Render', 08:25:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:30 INFO - 'HOME': '/Users/cltbld', 08:25:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:30 INFO - 'LOGNAME': 'cltbld', 08:25:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:30 INFO - 'MOZ_NO_REMOTE': '1', 08:25:30 INFO - 'NO_EM_RESTART': '1', 08:25:30 INFO - 'PAGER': '/bin/cat', 08:25:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:30 INFO - 'PWD': '/builds/slave/test', 08:25:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:30 INFO - 'SHELL': '/bin/bash', 08:25:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3ZZfBoKlW/Listeners', 08:25:30 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 08:25:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:30 INFO - 'USER': 'cltbld', 08:25:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:30 INFO - 'XPC_FLAGS': '0x0', 08:25:30 INFO - 'XPC_SERVICE_NAME': '0', 08:25:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:25:31 INFO - Cleaning up... 08:25:31 INFO - Return code: 0 08:25:31 INFO - Running post-action listener: _resource_record_post_action 08:25:31 INFO - Running post-action listener: _start_resource_monitoring 08:25:31 INFO - Starting resource monitoring. 08:25:31 INFO - [mozharness: 2016-12-16 16:25:31.142776Z] Finished create-virtualenv step (success) 08:25:31 INFO - [mozharness: 2016-12-16 16:25:31.143722Z] Running install step. 08:25:31 INFO - Running pre-action listener: _resource_record_pre_action 08:25:31 INFO - Running main action method: install 08:25:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:25:31 INFO - Reading from file tmpfile_stdout 08:25:31 INFO - Detecting whether we're running mozinstall >=1.0... 08:25:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:25:31 INFO - Reading from file tmpfile_stdout 08:25:31 INFO - Output received: 08:25:31 INFO - Usage: mozinstall [options] installer 08:25:31 INFO - Options: 08:25:31 INFO - -h, --help show this help message and exit 08:25:31 INFO - -d DEST, --destination=DEST 08:25:31 INFO - Directory to install application into. [default: 08:25:31 INFO - "/builds/slave/test"] 08:25:31 INFO - --app=APP Application being installed. [default: firefox] 08:25:31 INFO - mkdir: /builds/slave/test/build/application 08:25:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:25:53 INFO - Reading from file tmpfile_stdout 08:25:53 INFO - Output received: 08:25:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:25:53 INFO - Running post-action listener: _resource_record_post_action 08:25:53 INFO - [mozharness: 2016-12-16 16:25:53.961782Z] Finished install step (success) 08:25:53 INFO - [mozharness: 2016-12-16 16:25:53.961927Z] Running run-tests step. 08:25:53 INFO - Running pre-action listener: _resource_record_pre_action 08:25:53 INFO - Running main action method: run_tests 08:25:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:25:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:25:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:25:53 INFO - 2016-12-16 08:25:53.976 screenresolution[1625:7700] starting screenresolution argv=screenresolution get 08:25:53 INFO - 2016-12-16 08:25:53.979 screenresolution[1625:7700] Display 0: 1600x1200x32@60 08:25:53 INFO - 2016-12-16 08:25:53.984 screenresolution[1626:7703] starting screenresolution argv=screenresolution list 08:25:53 INFO - Available Modes on Display 0 08:25:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:25:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:25:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:25:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:25:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:25:54 INFO - Intel Iris: 08:25:54 INFO - Chipset Model: Intel Iris 08:25:54 INFO - Type: GPU 08:25:54 INFO - Bus: Built-In 08:25:54 INFO - VRAM (Dynamic, Max): 1536 MB 08:25:54 INFO - Vendor: Intel (0x8086) 08:25:54 INFO - Device ID: 0x0a2e 08:25:54 INFO - Revision ID: 0x0009 08:25:54 INFO - Displays: 08:25:54 INFO - Display: 08:25:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:25:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:25:54 INFO - Display Serial Number: 1600x1200 60 08:25:54 INFO - Main Display: Yes 08:25:54 INFO - Mirror: Off 08:25:54 INFO - Online: Yes 08:25:54 INFO - Rotation: Supported 08:25:54 INFO - Return code: 0 08:25:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:25:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:25:54 INFO - Python 2.7.3 08:25:54 INFO - Return code: 0 08:25:54 INFO - grabbing minidump binary from tooltool 08:25: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')]} 08:25:54 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 0x10be49d30>, '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 0x10bcb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be41b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:25:54 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 08:25:54 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 08:25:54 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 08:25:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:25:54 INFO - Return code: 0 08:25:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:25:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:25:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:25:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:25:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:25:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--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 08:25:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0117 --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 08:25:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBwJ9nndGN/Render', 08:25:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:54 INFO - 'HOME': '/Users/cltbld', 08:25:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:54 INFO - 'LOGNAME': 'cltbld', 08:25:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:25:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:54 INFO - 'MOZ_NO_REMOTE': '1', 08:25:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:25:54 INFO - 'NO_EM_RESTART': '1', 08:25:54 INFO - 'PAGER': '/bin/cat', 08:25:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:54 INFO - 'PWD': '/builds/slave/test', 08:25:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:25:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:54 INFO - 'SHELL': '/bin/bash', 08:25:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3ZZfBoKlW/Listeners', 08:25:54 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 08:25:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:54 INFO - 'USER': 'cltbld', 08:25:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:54 INFO - 'XPC_FLAGS': '0x0', 08:25:54 INFO - 'XPC_SERVICE_NAME': '0', 08:25:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--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 08:25:54 INFO - mozversion application_buildid: 20161216070349 08:25:54 INFO - mozversion application_changeset: 917fc78005ddd0f15013e8a9e0b473468a8b2f14 08:25:54 INFO - mozversion application_display_name: Nightly 08:25:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:25:54 INFO - mozversion application_name: Firefox 08:25:54 INFO - mozversion application_remotingname: firefox 08:25:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:25:54 INFO - mozversion application_vendor: Mozilla 08:25:54 INFO - mozversion application_version: 53.0a1 08:25:54 INFO - mozversion platform_buildid: 20161216070349 08:25:54 INFO - mozversion platform_changeset: 917fc78005ddd0f15013e8a9e0b473468a8b2f14 08:25:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:25:54 INFO - mozversion platform_version: 53.0a1 08:25:54 INFO - using testdate: 1481905554 08:25:54 INFO - actual date: 1481905554 08:25:54 INFO - starting webserver on 'localhost:49248' 08:25:54 INFO - SUITE-START | Running 2 tests 08:25:54 INFO - TEST-START | dromaeo_css 08:25:54 INFO - operating with platform_type : mac_ 08:25:54 INFO - Initialising browser for dromaeo_css test... 08:25:54 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpU60O06/profile http://localhost:49248/getInfo.html) 08:25:57 INFO - PROCESS | 1636 | 2016-12-16 08:25:57.515 plugin-container[1638:7868] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4b, name = 'com.apple.tsm.portname' 08:25:57 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:25:57 INFO - PROCESS | 1636 | 2016-12-16 08:25:57.517 plugin-container[1638:7868] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 08:25:57 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:25:57 INFO - PROCESS | 1636 | 2016-12-16 08:25:57.517 plugin-container[1638:7868] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:25:57 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 08:25:57 INFO - PROCESS | 1636 | colorDepth:24 08:25:57 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 08:25:57 INFO - PROCESS | 1636 | __metrics 08:25:57 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 08:25:58 INFO - TEST-INFO | 1636: exit 0 08:25:58 INFO - Browser initialized. 08:25:58 INFO - Running cycle 1/1 for dromaeo_css test... 08:25:58 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpU60O06/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:26:00 INFO - PROCESS | 1639 | 2016-12-16 08:26:00.789 plugin-container[1641:8019] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 08:26:00 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:26:00 INFO - PROCESS | 1639 | 2016-12-16 08:26:00.790 plugin-container[1641:8019] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 08:26:00 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:26:00 INFO - PROCESS | 1639 | 2016-12-16 08:26:00.790 plugin-container[1641:8019] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:26:10 INFO - PROCESS | 1639 | RSS: Main: 236900352 08:26:10 INFO - PROCESS | 1639 | 08:27:00 INFO - PROCESS | 1639 | 1481905620000 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] 08:29:13 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 08:29:15 INFO - PROCESS | 1639 | RSS: Main: 252977152 08:29:15 INFO - PROCESS | 1639 | 08:32:17 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 08:32:18 INFO - PROCESS | 1639 | RSS: Main: 264163328 08:32:18 INFO - PROCESS | 1639 | 08:35:20 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 08:35:20 INFO - PROCESS | 1639 | RSS: Main: 269914112 08:35:20 INFO - PROCESS | 1639 | 08:38:22 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 08:38:23 INFO - PROCESS | 1639 | RSS: Main: 268509184 08:38:23 INFO - PROCESS | 1639 | 08:41:26 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 08:41:27 INFO - PROCESS | 1639 | RSS: Main: 272932864 08:41:27 INFO - PROCESS | 1639 | 08:44:29 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 08:44:30 INFO - PROCESS | 1639 | RSS: Main: 275521536 08:44:30 INFO - PROCESS | 1639 | 08:44:30 INFO - PROCESS | 1639 | __start_tp_report 08:44:30 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 08:44:30 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 08:44:30 INFO - PROCESS | 1639 | |i|pagename|runs| 08:44:30 INFO - PROCESS | 1639 | |0;dojo.html;2953.6603290621574;3348.229907121362;3359.059463350263;3368.1747971746927;3387.3056023515182;5570.855534933843;5634.9154762678545;5640.576956728247;5682.971585142069;5743.10981797822;5524.917126243099;5639.68981706006;5654.830355089343;5678.921005009045;5728.79949201778;5153.149730294506;5159.251908473288;5175.378954525443;5193.896122077556;5246.763895624687;5232.8691782705355;5242.606804489678;5327.413984461706;5327.627066105374;5350.759215835296;4390.209762242795;5290.021346050974;5292.444293349201;5293.126634105368;5297.655652382583;274.15106849990633;5148.845534633967;5155;5236.345456817898;5337.5463085637575;2860.699626539204;5164.992826398854;5172.1207394742605;5195.116830138848;5233.11037123686;4252.617264446215;5392.110301800199;5627.521660658808;5649.604527683064;5669.659820410748;5291.576673866081;5295.364556253275;5451.781928722847;5456.91814622781;6223.190985171898;4663.790129444184;5125.256837758504;5830.892130495193;6164.537659675498;6189.473894718954;5810.970945145247;5813.069908814654;5817.301923769175;5831.737571809279;5848;5702.201691763156;6048.274207095089;6192.380761923771;6208.906866397007;6218.9378106219265;4803.759812009385;6003.939960600425;6100;6122.66325352101;6142.846428839315;5006.1739812930455;6116.244470650255;6139.492138333381;6139.8908086453375;6143.419763699168;5182.922256166161;6163.599366041193;6176.629402235881;6194.628322300677;6201.937980620226;6122.367382025772;6169.784057557964;6205.565610407228;6215.751369945242;6221.782237621662;4763.190257656207;4798.832040878552;4806.49531799163;4825.95174048262;4926.45808961014;4279.101388708406;4353.782310884443;4362.367456718758;4370.890727731832;4423.4912985006495;5641.774329026793;5687.687177205211;5721.170430287585;5732.828015159468;5853.912191317134;6135.938640613536;6156.8768624627255;6178.969105154535;6192.349803270682;6198.535109866688;5626.465485778845;5651.180578816049;5659.801906933238;5829.766809327664;5859.589828711949;5343.465653434625;5366.973165134149;5370.3287089113865;5391.838244852583;5416.377116631637;6267.6866156691985;6269.592476489013;6284.308726040041;6293.276273228637;6317.7157027932935;6156.5998210116195;6172.043333283348;6180.7527698891545;6186.381361863958;6188.195534580475;6102.7619317136805;6116.761878222105;6174.567780255339;6201.503879689524;6269.529785266214;5377.300950876361;6114.357992410733;6117.602355846856;6128.503123582287;6184.5052395809125;6018.398160183947;6110.114033465212;6168.444839964424;6180.845478863064;6199.845003874939;5931.733072011856;5959.433853783884;5976.820695379146;6110.541709371903;6154.261488621394;6023.066424704178;6039.849003774941;6058.485028772604;6065.3934606539;6066.878662426639;5856.853578660568;5882.882342353043;5964.045752679551;6013.128096426081;6023.969880150571;6026.035834266497;6107.236595425572;6112.419320164578;6122;6143.662098584442;4863.95136048635;4918.262260660929;5255.580993131909;5853.824385268278;5987.6108052976415 08:44:30 INFO - PROCESS | 1639 | |1;ext.html;449.209391471011;455.21475454840385;487.461355202501;487.8780304923769;499.6802046690119;35947.46078808817;38038.23923521531;38091;38116.99999999996;38198;39593.60406395935;39989.60010399895;40077.19845603086;40154.79922600383;40265.396019059735;8302.211289927447;8550.957245213796;9232.446053236794;9467.005964373744;9488.478133702643;11028;11318.490667919963;11347.035501982342;11471.139664525152;11478.655640330804;11079.833802492929;11238.269512481704;11253.493592788343;11322.60370887019;11331.886681133206;11175;11222.438878056064;11228.775424491547;11245.15661325404;11254.212205145643;11021.614243501519;11037.227394082418;11124.221304508688;11190.720231994264;11222.158338124673;37751.43372849409;37790;38148.42777358342;38191;38325.80837095824;3287.7862938909134;3345.548350972612;3396.9830150849334;3468.8612455501748;3700.85196592136;24046.196535259925;28721.425571488664;29416;29648.258793530335;29816.8509157455;3074.415860986428;3145.308032232928;3156.542301366312;3185;3252.7723059385853;446504;452090;452320;453225;453368;44107.77946110313;54926.45073549214;55167.72416137974;55313.89372212533;55318.72340638271;57776;57956;58291.417085829446;58334.416655833746;58378.7081064592;14350.49773257931;15081.095134291758;15341.616459588377;15524.534263972098;15534.378624854879;10937.726556836144;10997.780044399067;11293.604723834791;11980;12235.143539952296;14778.704425911421;14930.850691492946;14975.400983960737;15041.247937603077;15138.27511048022;7116;7176.928230717626;7196.373323736799;7222.313880181375;7234.974207221986;875.7022612311845;1023.6263763726187;1041.906228439429;1092.7814437112609;1157.774234024374;28745.137645870658;29101.563476547868;29186;29390;29626.259343516584;826.4545400036068;916.6929078758691;1018.0481250031245;1031.7369070886875;1120.7478317378525;14808.185549794865;14947;15093.320800564;15158.16630085356;15274.847251527342;553939;763080;773390;776945;781850;49016.50983490191;49747.75630609191;49794.50205497971;49888;50233;1345.8586848380976;1366.152985149194;1370.4244217428704;1378.5726424808342;1386.7989141574415;25597.488050239263;26101.08646197374;27231.45537089287;27621.44757104887;27855.771538277295;42754.78622606867;42966.57033429626;43000.13999720051;43194.784026079666;43413.224545300625;530347;542107;544769;544981;547191;22992.4251893704;23076.076956921534;23760.881195593913;23934.04263829427;24482.877585611957;33609.26986793002;39755.64096946969;39800.60199397969;39948;40742.79628601838;29384.412311753218;32414.513782293758;33319.33361332808;33863.153421164665;34376.31247374989;26846.865765671046;33639.66360336365;33698.15754606154;34550;34768.30463390768;32809.17977050593;33508.8271910482;33549.16127096842;33830;34067.82966085154;28384.85807570949;30049;30217.848910755307;30572;30656;22771.772282276965;23267.650985234897;23472.295831125095;23506.88246558756;23557.52884942326 08:44:30 INFO - PROCESS | 1639 | |2;jquery.html;326.73860911270987;369.96855267302277;374.17494424793335;388.63080073929774;400.507375927609;18041.458756237316;18577.814221857778;19652.90173549132;19656.508587285323;19855.602887942234;14012.509562165325;19709.50726231845;20415;20686.069126889288;20716.8605726685;5599.832005039855;5794.188813566104;5829.854253643672;5830.912536311958;5831.416858314177;5387.973060134694;5662.8301150965535;5733.197352370671;5733.827985160452;5767.134929760548;5613.821097569495;5693.28833895763;5695.971520142393;5700.030994730885;5701.315842098933;5532.1425179097305;5670.773169073233;5692.77228910843;5716.256886604715;5717.599768016241;4920.3111564381015;5033.219850923113;5042.218456139304;5051.444341122474;5052.065367906949;10952.34285942846;12529.686757831036;13041.804372934414;13097.869021309856;13109.278989655566;2229.2197730794255;2238.6194346960892;2281.0305620111385;2392.2823153054187;2555.2845203343095;9080.273578113734;9464.763380915532;9846;10014.849777253346;10051.24615653829;1639.5737108351796;1732.0300631646192;1735.2191513818832;1773.9822601774076;1784.518180091368;97814;101017.99999999926;101064;101921.49039254831;102205.48897255467;11209.159313051554;11236.381998990135;11431.256968297033;11459.140564457532;11654.242474239147;14297.285135743172;14315.928420357832;14318.498852540111;14342.641433964025;14345.282735863166;8092.878606820902;8284;8328.708495202638;8403.117672644406;8435.915640843636;8145.674173033012;8163.224493673091;8195.67217311301;8299.419040667095;8333;4371.213181627338;4509.774511274423;4514.480834703985;4543.341215523797;4580.954190458119;1654.4044144108111;1890.4990177602947;1936.7772706138692;1942.339604534465;1943.319838056669;824.3281725393784;869.8390797702446;897.6768363389175;962.0908241711516;964.4358050540512;16246;19996;20631.65894216871;20635.69046464304;20983.580328393346;868.6568805321863;907.1880666803306;933.2114363362947;955.3408148377597;968.6416026070258;9598.280128990353;9743.415395076318;10801.621943231949;11262.887371126348;11296.887031129583;384969;530571;542731;543678;547355;105759.94240057655;106711;110572;125481;126848;946.7349142240183;1000.1898462245605;1016.364772017489;1021.5862575656871;1022.4693882640405;15743.13412762309;18905.77930664197;18998.335058272893;19017.714734279274;19113.23547058102;44204.77897610491;44336.77831610821;49881.50118498768;51153.488465114875;54165.45834541604;59389.406105940114;76577.31979380695;78264.60867695625;78807.60596197212;79264.20735792568;7645.923540764521;7892.210778922292;8017.518948863087;8070;8351.540665263468;10531.789364212826;12202.267863928193;12310.569130080405;12540.623781286577;12818.294993775431;10693.839592405964;10976.12191024732;11094;11120.61077862271;11178.10575154002;10391.48042597852;10783.6225732099;10888.29226100301;11102.888971110186;11246.83129753038;8904.86642700373;9360.063993600585;9722.125008749246;9779.804403912025;10988.890111099106;7094.290570942864;7644.159142494433;7878.803029924297;8278.130796266305;8284.212999765012;10633.734156646145;10896.782064358827;10908.018278354986;11231.887681123411;11237.38194399316 08:44:30 INFO - PROCESS | 1639 | |3;mootools.html;1166.428450059471;1471.8454562270958;1610.1868556379027;1804.657115148121;1838.8620853435987;6690.8291049066465;7566.924330756705;7826.8043298917555;8218.301444377228;8600.784980375485;7042.929570704291;8068;8365.91634083659;8465.153484651548;8473.025602055766;2279.5896738587007;2321.7562155973633;2331;2333.3583264602203;2337.392278007722;2339.029302839328;2349.01341436597;2355.9057637694473;2361.681173041635;2375.5130198309307;2299.7585253548386;2322.256877799105;2340.6840076589615;2342.437814924423;2350.7766762157567;2291.3126062181364;2297.5519773644146;2314.7685231476717;2319.118734880743;2336.8714720690355;2297.5060362022245;2315.3285547191294;2345.683332750091;2356.516914032636;2358.1982126077046;8404.117567655368;8433.072362040171;8491.95754021226;8503;8507.787305317355;5074.517920797555;5209.557187639055;5231.058409486291;5238.397584277819;5239.580833533309;3201.27971206479;3312.403767321905;3449.4998225257195;3467.6705712957482;3470.7396945229207;963.4460185393427;1017.2574020964663;1023.5240613114937;1039.2465462539674;1043.2175868098925;10066.446345450924;10911.508982095826;10915.235933484659;10960.958708922639;11056.944715276453;4429.136318417877;4484.260097083945;4632.86101416958;4697.295405689174;4710.623150148049;9304.116108969638;9417.670381536751;9419.387739796892;9431.858522122173;9433.198178154797;4400.471943366816;5122.743862806845;5226.163813789775;5234.241035049897;5236.345456817898;4055.351143817034;4286.892827679333;4370.494942429565;4749.691270067435;4781.497942716034;3787.9242415151543;3845.5193100862393;3888.5528164260904;3909.8045097745;3932.9606703932595;1276.0812215205037;1279.6864768131868;1289.3231053696697;1294.5339677716015;1297.2216669998115;600.1957377114687;701;885.5041176940933;894.2845723421237;920.3557509743207;5501.972490137524;5588;5611.607187496836;5652.8869422611315;5709.800156994485;754.4718696912183;910.8406028944908;915.2632131134501;940.1115945431638;956.8038552096804;3776.86780962665;3790.6967442604528;3820.3696390095324;3860.478835357247;3932.410138479194;8168.2240187183315;9893.851592226123;10151.441670708035;10260.486975651187;10453.634122805664;9990.200783937267;10212.131968782736;10230.693079207636;10274.743131421774;10311.639092631873;6810.842156833351;7386.96306518464;7739.264769846855;7764.145943946161;7909.841803164019;5728.656280623176;5816.651000939957;5878.500327472213;5931.495822855106;5955.880882382415;8911.197992180734;8986.820263594822;9082.954585227297;9383.671571494964;9458.574364153492;9441.763955900882;9467.147956683932;9562.474063926551;9564.378315409476;9688.41869487833;6630.801075967729;6805.285445028201;7131.001659767533;7198.172210195895;7380.409567234501;7911.485753426009;8081.111077781322;8090.150534193825;8108.878366824384;8112.75661730149;7174.8565028698085;7270.418366530559;7326.931288916246;7366.447516436181;7399.667014984447;7048.330408611174;7092.361687448154;7130.500864939404;7247.927520724726;7280.745173918887;6618.801435956929;6780.627065511247;6787.592744435349;6798;6817.318268173143;4594.47163576193;4829.468758436639;4915.21356582945;4948.628852836124;5003.074431230234;4451.020775429401;4867.634927380532;4942.258661200849;4997.9500204997485;5019.84940451787 08:44:30 INFO - PROCESS | 1639 | |4;prototype.html;348.90928358626763;352.3235388054934;353.2264341093006;362.9038304849215;369.5842177550254;4572.051027087535;5000.274960130783;5199.402068762093;5366.9463305366935;5485.451454854512;5130.897382052366;5144.202648589475;5159.910236354273;5338.131653921126;5385.180263664019;3322.784019038767;3331.083951913219;3530.3115892400992;3919.9804000979957;4221.535631080586;3507.1582820123112;4241.5122260939925;4262.147570485899;4278.208531421681;4284.6786491013145;3500.982495087521;4024.859129930453;4040.2121586290687;4046.433499310099;4071.3282308419075;3698.4267438546935;4015.112896938449;4024.3561030235023;4048.5344185418758;4068.9389659155136;2936.6769655337894;3337.2658015236607;3491.738119641037;3498.5276987606603;3504.229069604683;4948.7278199699;5228.137357336035;5277.155655095166;5281.947180528184;5323.467653234684;4112.403701463271;4214.978925105354;4605.884852878671;4673.018666080127;4674.11191873543;5480.506754392084;5738.655680659173;5755.597108202427;5778.913316300259;5783.392743761929;3611.8916432507067;3794.8671796487265;3844.3464611016193;3895.2014836958356;3911.3546264866263;4895.804167833282;5283.973580132075;5703.43345494525;5703.971480142573;5979.760809567612;3328.900132996014;3900.3759398496104;3911.7923302891354;3936.488256526633;3936.618345900746;3310.9570485297054;3980.482537270136;3988.3818008208773;3990.1221731219084;3996.2606917720314;4175.436316097349;4453.153900758845;4506.7070640408265;4627.7143228307605;4692.41344831896;5795.478406943395;5805.448482394166;5850.61970971894;5860.560457965669;5940.970295148497;1161.372858656316;1167.9240849344765;1214.9794172894635;1225.7548490301974;1283.8908692761033;848.8047749017637;863.8617821148712;870.2646263906988;873.5588527793424;876.7287433221854;4817.445993710698;5145.614078944094;5157.575939308519;5173.534381905646;5191.844244672666;4721.834735784231;4909.288153217834;5577.358603760538;5682.232898558725;5728.742206600713;3956.188981258835;4339.327404252346;4956.553910148104;5100.897982040413;5125.205593133071;2526.0527302261653;2625.647791387437;2786.958195627067;2870.942581148365;2995.266159790865;5342.515730185482;5918.733656985446;5928.79249226275;5959.960420185736;6040.335563088057;5109.74451277442;5200.76596553156;5340.184337325328;5488.835334939959;5689.971550142222;4764.689125773997;5124.769385377667;5249.658772179872;5475.863103422366;5695.800646977336;5284.471552844839;5295.319752771787;5356.9286142770825;5553.778168802857;5719.139580783038;4880.170371036861;5356.5714742821265;5563.720344320748;5881.617694849821;5922.703864806828;4843.539863712942;4900.632452566;6009.038553831366;6194.938050619436;6200.472959798468;3264.8452021665585;3966.781826999543;3974.006498375406;3982.7012974027643;4009.6391324779743;4581.5189405113315;5141;5412.161115027177;5904.5866789326055;5958.553108516792;4806.663533552618;5393.784248630168;5795.797147099831;5811.709414529172;5821.970890145522;5134.640575159703;5667;5824.301083869963;5831.970840145772;5845.327787304344;5107.876267221205;5315.707636080053;5417.914103070268;5594.4125866785025;5709.885802284014;5686.658800471985;5686.905428091767;5714.571407144397;5717.971410142923;5728.3985181557;5188.377394712658;5450.937067271844;5604.747786349542;5643.209950607001;5678.403767604342 08:44:30 INFO - PROCESS | 1639 | |5;yui.html;714.7819914925947;723.4790950515627;724.855028994201;740.0231694163706;752;14266;15206.847931520682;15271.923640381783;15320.693586128285;15324.540263792076;13117.737645247089;13786.517471888486;14018.369173387196;14050.508232211874;14098.506552270672;847.2332539052168;867.9305655547572;872.520113937335;873.0352959979227;875.0330884372772;685.4721864164591;707.2891743797476;842.8609279468911;859.6733241368271;865.4720620421538;893.4505279253245;916.083916083916;917.0920788419484;919.7746552094746;920.8296465153968;901.2699713232303;907.9092090790934;908.900020997693;909.8316811389914;911.3893691226893;663.9468842492589;669.3674477618653;675.9898601520981;677.2584020497547;678.2505331608551;12243.449044793006;12599.874001260054;15265.236738163047;15293.464728734552;15398.923005385013;14182.361793719307;14416.207108609022;14588.854111458855;14737.852621473756;14781.33483993223;10182.338148020355;10633.308834925705;11201.775964480747;11242.437878106062;11396.430178491042;3156.8421578921193;3655.067957670777;3658.817059147032;3676.485292059114;3715.777053376806;110180;110270.44864775705;117158;119750.40124799407;120288.39855800752;10737.83893241602;10969.50637221311;11044.061254793272;11056.778864422666;11300.095992320594;23227.419314516934;23483.88258058699;23554.88222558876;23688.881555592456;23693.644595331083;7819.452638315377;7864.567448790372;7895.44089814602;7896.486728362638;7905.683772649029;4225.830966761357;4316.589923957219;4393.714408563433;4427.26950053244;4469.843555475542;3295.6374798772117;3325.4961345341208;3447.5345828313357;3540.4689296605716;3564.697000754965;627.6046090962665;639.3957709964039;651.739304278283;654.0385633119307;662.0499583098269;610.6183635227983;651.8989556618732;657.3853447027045;668.7127279986007;685.6194811879454;7780.416468764865;7835.373170146375;8975.910240897638;9048.321375896834;9149.54252287383;488.93154958305865;537.1164434505217;572.7279542217414;672.2470832667444;672.8116127484312;4334.133173365277;4431.158079964861;4511.774411279423;4615.423072115986;4711.552402521755;100791.49604251931;102375.4881225589;104757;105327;110006;87295.56352218325;92728.53635731777;93596;93980.53009734908;96412;60489.395106048374;61343.38656613377;67362.98955515672;71832;72599.63700181466;9375.390599611004;9776.693470088818;9862.901370986197;9955.502224888582;9958.253131015057;36810.079748006516;44849.55150448454;47396.7630161847;49619.778499160406;51102.48897511126;62765;67454.9881751784;67823.98264025945;68138.63722725617;68526.65736671285;12510.061745369316;12841.229526228457;12894.67763305925;13096.672583185496;13416.32918354098;13386.661333866536;13564.93217533906;14231.644208894446;14386.208758518382;14771.556853294418;13435.328233588478;13442.059055865995;13527.729445411234;13611.387487563281;14054.578362649137;13400.99492538044;13604.387802549107;13640.590782276548;13775.724485510033;14074.718505629626;11338.036266917074;12912;13179.802302965272;13544.789125130394;13853.37659805291;10915.508802104083;11215.88784112181;11221.438928053727;11378.655827958488;11414.258073225214;9843.557039933363;11017.44912754375;11171.608993685182;11450.141239406912;11460.770784584429 08:44:30 INFO - PROCESS | 1639 | __end_tp_report 08:44:30 INFO - PROCESS | 1639 | __start_cc_report 08:44:30 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,388 08:44:30 INFO - PROCESS | 1639 | __end_cc_report 08:44:30 INFO - PROCESS | 1639 | __startTimestamp1481906670161__endTimestamp 08:44:30 INFO - PROCESS | 1639 | 08:44:30 INFO - PROCESS | 1639 | ------- Summary: start ------- 08:44:30 INFO - PROCESS | 1639 | Number of tests: 6 08:44:30 INFO - PROCESS | 1639 | 08:44:30 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5571.17 Median:5813.07 stddev:803.56 (13.8%) stddev-sans-first:779.34 08:44:30 INFO - PROCESS | 1639 | Values: 2953.7 3348.2 3359.1 3368.2 3387.3 5570.9 5634.9 5640.6 5683.0 5743.1 5524.9 5639.7 5654.8 5678.9 5728.8 5153.1 5159.3 5175.4 5193.9 5246.8 5232.9 5242.6 5327.4 5327.6 5350.8 4390.2 5290.0 5292.4 5293.1 5297.7 274.2 5148.8 5155.0 5236.3 5337.5 2860.7 5165.0 5172.1 5195.1 5233.1 4252.6 5392.1 5627.5 5649.6 5669.7 5291.6 5295.4 5451.8 5456.9 6223.2 4663.8 5125.3 5830.9 6164.5 6189.5 5811.0 5813.1 5817.3 5831.7 5848.0 5702.2 6048.3 6192.4 6208.9 6218.9 4803.8 6003.9 6100.0 6122.7 6142.8 5006.2 6116.2 6139.5 6139.9 6143.4 5182.9 6163.6 6176.6 6194.6 6201.9 6122.4 6169.8 6205.6 6215.8 6221.8 4763.2 4798.8 4806.5 4826.0 4926.5 4279.1 4353.8 4362.4 4370.9 4423.5 5641.8 5687.7 5721.2 5732.8 5853.9 6135.9 6156.9 6179.0 6192.3 6198.5 5626.5 5651.2 5659.8 5829.8 5859.6 5343.5 5367.0 5370.3 5391.8 5416.4 6267.7 6269.6 6284.3 6293.3 6317.7 6156.6 6172.0 6180.8 6186.4 6188.2 6102.8 6116.8 6174.6 6201.5 6269.5 5377.3 6114.4 6117.6 6128.5 6184.5 6018.4 6110.1 6168.4 6180.8 6199.8 5931.7 5959.4 5976.8 6110.5 6154.3 6023.1 6039.8 6058.5 6065.4 6066.9 5856.9 5882.9 5964.0 6013.1 6024.0 6026.0 6107.2 6112.4 6122.0 6143.7 4864.0 4918.3 5255.6 5853.8 5987.6 08:44:30 INFO - PROCESS | 1639 | 08:44:30 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:68616.68 Median:18048.48 stddev:158036.54 (875.6%) stddev-sans-first:158396.99 08:44:30 INFO - PROCESS | 1639 | Values: 449.2 455.2 487.5 487.9 499.7 35947.5 38038.2 38091.0 38117.0 38198.0 39593.6 39989.6 40077.2 40154.8 40265.4 8302.2 8551.0 9232.4 9467.0 9488.5 11028.0 11318.5 11347.0 11471.1 11478.7 11079.8 11238.3 11253.5 11322.6 11331.9 11175.0 11222.4 11228.8 11245.2 11254.2 11021.6 11037.2 11124.2 11190.7 11222.2 37751.4 37790.0 38148.4 38191.0 38325.8 3287.8 3345.5 3397.0 3468.9 3700.9 24046.2 28721.4 29416.0 29648.3 29816.9 3074.4 3145.3 3156.5 3185.0 3252.8 446504.0 452090.0 452320.0 453225.0 453368.0 44107.8 54926.5 55167.7 55313.9 55318.7 57776.0 57956.0 58291.4 58334.4 58378.7 14350.5 15081.1 15341.6 15524.5 15534.4 10937.7 10997.8 11293.6 11980.0 12235.1 14778.7 14930.9 14975.4 15041.2 15138.3 7116.0 7176.9 7196.4 7222.3 7235.0 875.7 1023.6 1041.9 1092.8 1157.8 28745.1 29101.6 29186.0 29390.0 29626.3 826.5 916.7 1018.0 1031.7 1120.7 14808.2 14947.0 15093.3 15158.2 15274.8 553939.0 763080.0 773390.0 776945.0 781850.0 49016.5 49747.8 49794.5 49888.0 50233.0 1345.9 1366.2 1370.4 1378.6 1386.8 25597.5 26101.1 27231.5 27621.4 27855.8 42754.8 42966.6 43000.1 43194.8 43413.2 530347.0 542107.0 544769.0 544981.0 547191.0 22992.4 23076.1 23760.9 23934.0 24482.9 33609.3 39755.6 39800.6 39948.0 40742.8 29384.4 32414.5 33319.3 33863.2 34376.3 26846.9 33639.7 33698.2 34550.0 34768.3 32809.2 33508.8 33549.2 33830.0 34067.8 28384.9 30049.0 30217.8 30572.0 30656.0 22771.8 23267.7 23472.3 23506.9 23557.5 08:44:30 INFO - PROCESS | 1639 | 08:44:30 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:30897.19 Median:372.07 stddev:85911.16 (23089.9%) stddev-sans-first:86121.50 08:44:30 INFO - PROCESS | 1639 | Values: 326.7 370.0 374.2 388.6 400.5 18041.5 18577.8 19652.9 19656.5 19855.6 14012.5 19709.5 20415.0 20686.1 20716.9 5599.8 5794.2 5829.9 5830.9 5831.4 5388.0 5662.8 5733.2 5733.8 5767.1 5613.8 5693.3 5696.0 5700.0 5701.3 5532.1 5670.8 5692.8 5716.3 5717.6 4920.3 5033.2 5042.2 5051.4 5052.1 10952.3 12529.7 13041.8 13097.9 13109.3 2229.2 2238.6 2281.0 2392.3 2555.3 9080.3 9464.8 9846.0 10014.8 10051.2 1639.6 1732.0 1735.2 1774.0 1784.5 97814.0 101018.0 101064.0 101921.5 102205.5 11209.2 11236.4 11431.3 11459.1 11654.2 14297.3 14315.9 14318.5 14342.6 14345.3 8092.9 8284.0 8328.7 8403.1 8435.9 8145.7 8163.2 8195.7 8299.4 8333.0 4371.2 4509.8 4514.5 4543.3 4581.0 1654.4 1890.5 1936.8 1942.3 1943.3 824.3 869.8 897.7 962.1 964.4 16246.0 19996.0 20631.7 20635.7 20983.6 868.7 907.2 933.2 955.3 968.6 9598.3 9743.4 10801.6 11262.9 11296.9 384969.0 530571.0 542731.0 543678.0 547355.0 105759.9 106711.0 110572.0 125481.0 126848.0 946.7 1000.2 1016.4 1021.6 1022.5 15743.1 18905.8 18998.3 19017.7 19113.2 44204.8 44336.8 49881.5 51153.5 54165.5 59389.4 76577.3 78264.6 78807.6 79264.2 7645.9 7892.2 8017.5 8070.0 8351.5 10531.8 12202.3 12310.6 12540.6 12818.3 10693.8 10976.1 11094.0 11120.6 11178.1 10391.5 10783.6 10888.3 11102.9 11246.8 8904.9 9360.1 9722.1 9779.8 10988.9 7094.3 7644.2 7878.8 8278.1 8284.2 10633.7 10896.8 10908.0 11231.9 11237.4 08:44:30 INFO - PROCESS | 1639 | 08:44:30 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5440.24 Median:5228.61 stddev:2973.93 (56.9%) stddev-sans-first:2964.92 08:44:30 INFO - PROCESS | 1639 | Values: 1166.4 1471.8 1610.2 1804.7 1838.9 6690.8 7566.9 7826.8 8218.3 8600.8 7042.9 8068.0 8365.9 8465.2 8473.0 2279.6 2321.8 2331.0 2333.4 2337.4 2339.0 2349.0 2355.9 2361.7 2375.5 2299.8 2322.3 2340.7 2342.4 2350.8 2291.3 2297.6 2314.8 2319.1 2336.9 2297.5 2315.3 2345.7 2356.5 2358.2 8404.1 8433.1 8492.0 8503.0 8507.8 5074.5 5209.6 5231.1 5238.4 5239.6 3201.3 3312.4 3449.5 3467.7 3470.7 963.4 1017.3 1023.5 1039.2 1043.2 10066.4 10911.5 10915.2 10961.0 11056.9 4429.1 4484.3 4632.9 4697.3 4710.6 9304.1 9417.7 9419.4 9431.9 9433.2 4400.5 5122.7 5226.2 5234.2 5236.3 4055.4 4286.9 4370.5 4749.7 4781.5 3787.9 3845.5 3888.6 3909.8 3933.0 1276.1 1279.7 1289.3 1294.5 1297.2 600.2 701.0 885.5 894.3 920.4 5502.0 5588.0 5611.6 5652.9 5709.8 754.5 910.8 915.3 940.1 956.8 3776.9 3790.7 3820.4 3860.5 3932.4 8168.2 9893.9 10151.4 10260.5 10453.6 9990.2 10212.1 10230.7 10274.7 10311.6 6810.8 7387.0 7739.3 7764.1 7909.8 5728.7 5816.7 5878.5 5931.5 5955.9 8911.2 8986.8 9083.0 9383.7 9458.6 9441.8 9467.1 9562.5 9564.4 9688.4 6630.8 6805.3 7131.0 7198.2 7380.4 7911.5 8081.1 8090.2 8108.9 8112.8 7174.9 7270.4 7326.9 7366.4 7399.7 7048.3 7092.4 7130.5 7247.9 7280.7 6618.8 6780.6 6787.6 6798.0 6817.3 4594.5 4829.5 4915.2 4948.6 5003.1 4451.0 4867.6 4942.3 4998.0 5019.8 08:44:30 INFO - PROCESS | 1639 | 08:44:30 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4515.06 Median:5128.05 stddev:1410.15 (27.5%) stddev-sans-first:1379.00 08:44:30 INFO - PROCESS | 1639 | Values: 348.9 352.3 353.2 362.9 369.6 4572.1 5000.3 5199.4 5366.9 5485.5 5130.9 5144.2 5159.9 5338.1 5385.2 3322.8 3331.1 3530.3 3920.0 4221.5 3507.2 4241.5 4262.1 4278.2 4284.7 3501.0 4024.9 4040.2 4046.4 4071.3 3698.4 4015.1 4024.4 4048.5 4068.9 2936.7 3337.3 3491.7 3498.5 3504.2 4948.7 5228.1 5277.2 5281.9 5323.5 4112.4 4215.0 4605.9 4673.0 4674.1 5480.5 5738.7 5755.6 5778.9 5783.4 3611.9 3794.9 3844.3 3895.2 3911.4 4895.8 5284.0 5703.4 5704.0 5979.8 3328.9 3900.4 3911.8 3936.5 3936.6 3311.0 3980.5 3988.4 3990.1 3996.3 4175.4 4453.2 4506.7 4627.7 4692.4 5795.5 5805.4 5850.6 5860.6 5941.0 1161.4 1167.9 1215.0 1225.8 1283.9 848.8 863.9 870.3 873.6 876.7 4817.4 5145.6 5157.6 5173.5 5191.8 4721.8 4909.3 5577.4 5682.2 5728.7 3956.2 4339.3 4956.6 5100.9 5125.2 2526.1 2625.6 2787.0 2870.9 2995.3 5342.5 5918.7 5928.8 5960.0 6040.3 5109.7 5200.8 5340.2 5488.8 5690.0 4764.7 5124.8 5249.7 5475.9 5695.8 5284.5 5295.3 5356.9 5553.8 5719.1 4880.2 5356.6 5563.7 5881.6 5922.7 4843.5 4900.6 6009.0 6194.9 6200.5 3264.8 3966.8 3974.0 3982.7 4009.6 4581.5 5141.0 5412.2 5904.6 5958.6 4806.7 5393.8 5795.8 5811.7 5822.0 5134.6 5667.0 5824.3 5832.0 5845.3 5107.9 5315.7 5417.9 5594.4 5709.9 5686.7 5686.9 5714.6 5718.0 5728.4 5188.4 5450.9 5604.7 5643.2 5678.4 08:44:30 INFO - PROCESS | 1639 | 08:44:30 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20391.39 Median:4271.21 stddev:29974.62 (701.8%) stddev-sans-first:30022.30 08:44:30 INFO - PROCESS | 1639 | Values: 714.8 723.5 724.9 740.0 752.0 14266.0 15206.8 15271.9 15320.7 15324.5 13117.7 13786.5 14018.4 14050.5 14098.5 847.2 867.9 872.5 873.0 875.0 685.5 707.3 842.9 859.7 865.5 893.5 916.1 917.1 919.8 920.8 901.3 907.9 908.9 909.8 911.4 663.9 669.4 676.0 677.3 678.3 12243.4 12599.9 15265.2 15293.5 15398.9 14182.4 14416.2 14588.9 14737.9 14781.3 10182.3 10633.3 11201.8 11242.4 11396.4 3156.8 3655.1 3658.8 3676.5 3715.8 110180.0 110270.4 117158.0 119750.4 120288.4 10737.8 10969.5 11044.1 11056.8 11300.1 23227.4 23483.9 23554.9 23688.9 23693.6 7819.5 7864.6 7895.4 7896.5 7905.7 4225.8 4316.6 4393.7 4427.3 4469.8 3295.6 3325.5 3447.5 3540.5 3564.7 627.6 639.4 651.7 654.0 662.0 610.6 651.9 657.4 668.7 685.6 7780.4 7835.4 8975.9 9048.3 9149.5 488.9 537.1 572.7 672.2 672.8 4334.1 4431.2 4511.8 4615.4 4711.6 100791.5 102375.5 104757.0 105327.0 110006.0 87295.6 92728.5 93596.0 93980.5 96412.0 60489.4 61343.4 67363.0 71832.0 72599.6 9375.4 9776.7 9862.9 9955.5 9958.3 36810.1 44849.6 47396.8 49619.8 51102.5 62765.0 67455.0 67824.0 68138.6 68526.7 12510.1 12841.2 12894.7 13096.7 13416.3 13386.7 13564.9 14231.6 14386.2 14771.6 13435.3 13442.1 13527.7 13611.4 14054.6 13401.0 13604.4 13640.6 13775.7 14074.7 11338.0 12912.0 13179.8 13544.8 13853.4 10915.5 11215.9 11221.4 11378.7 11414.3 9843.6 11017.4 11171.6 11450.1 11460.8 08:44:30 INFO - PROCESS | 1639 | -------- Summary: end -------- 08:44:30 INFO - PROCESS | 1639 | 08:44:30 INFO - TEST-INFO | 1639: exit 0 08:44:30 INFO - TEST-OK | dromaeo_css | took 1116242ms 08:44:30 INFO - TEST-START | kraken 08:44:30 INFO - operating with platform_type : mac_ 08:44:30 INFO - Initialising browser for kraken test... 08:44:30 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpYlsiKc/profile http://localhost:49248/getInfo.html) 08:44:33 INFO - PROCESS | 1650 | 2016-12-16 08:44:33.637 plugin-container[1651:10234] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 08:44:33 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:33 INFO - PROCESS | 1650 | 2016-12-16 08:44:33.639 plugin-container[1651:10234] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460f, name = 'com.apple.CFPasteboardClient' 08:44:33 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:33 INFO - PROCESS | 1650 | 2016-12-16 08:44:33.639 plugin-container[1651:10234] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:44:33 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 08:44:33 INFO - PROCESS | 1650 | colorDepth:24 08:44:33 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 08:44:33 INFO - PROCESS | 1650 | __metrics 08:44:33 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 08:44:34 INFO - TEST-INFO | 1650: exit 0 08:44:34 INFO - Browser initialized. 08:44:34 INFO - Running cycle 1/1 for kraken test... 08:44:34 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpYlsiKc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:44:37 INFO - PROCESS | 1652 | 2016-12-16 08:44:37.186 plugin-container[1653:10350] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 08:44:37 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:37 INFO - PROCESS | 1652 | 2016-12-16 08:44:37.186 plugin-container[1653:10350] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 08:44:37 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:37 INFO - PROCESS | 1652 | 2016-12-16 08:44:37.186 plugin-container[1653:10350] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:44:46 INFO - PROCESS | 1652 | RSS: Main: 237019136 08:44:46 INFO - PROCESS | 1652 | 08:44:49 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 08:44:50 INFO - PROCESS | 1652 | RSS: Main: 249290752 08:44:50 INFO - PROCESS | 1652 | 08:44:53 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 08:44:54 INFO - PROCESS | 1652 | RSS: Main: 256958464 08:44:54 INFO - PROCESS | 1652 | 08:44:57 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 08:44:58 INFO - PROCESS | 1652 | RSS: Main: 259551232 08:44:58 INFO - PROCESS | 1652 | 08:45:00 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 08:45:00 INFO - PROCESS | 1652 | RSS: Main: 260767744 08:45:00 INFO - PROCESS | 1652 | 08:45:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:45:03 INFO - PROCESS | 1652 | RSS: Main: 262451200 08:45:03 INFO - PROCESS | 1652 | 08:45:06 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 08:45:07 INFO - PROCESS | 1652 | RSS: Main: 267919360 08:45:07 INFO - PROCESS | 1652 | 08:45:10 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 08:45:11 INFO - PROCESS | 1652 | RSS: Main: 266919936 08:45:11 INFO - PROCESS | 1652 | 08:45:14 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 08:45:14 INFO - PROCESS | 1652 | RSS: Main: 267165696 08:45:14 INFO - PROCESS | 1652 | 08:45:16 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:45:17 INFO - PROCESS | 1652 | RSS: Main: 266412032 08:45:17 INFO - PROCESS | 1652 | 08:45:19 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:45:19 INFO - PROCESS | 1652 | RSS: Main: 266571776 08:45:19 INFO - PROCESS | 1652 | 08:45:21 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:45:22 INFO - PROCESS | 1652 | RSS: Main: 266432512 08:45:22 INFO - PROCESS | 1652 | 08:45:25 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:45:26 INFO - PROCESS | 1652 | RSS: Main: 266698752 08:45:26 INFO - PROCESS | 1652 | 08:45:29 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:45:30 INFO - PROCESS | 1652 | RSS: Main: 266522624 08:45:30 INFO - PROCESS | 1652 | 08:45:31 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 08:45:32 INFO - PROCESS | 1652 | RSS: Main: 268718080 08:45:32 INFO - PROCESS | 1652 | 08:45:32 INFO - PROCESS | 1652 | __start_tp_report 08:45:32 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 08:45:32 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 08:45:32 INFO - PROCESS | 1652 | |i|pagename|runs| 08:45:32 INFO - PROCESS | 1652 | |0;ai-astar;106;119;119;122;118;118;121;119;121;119 08:45:32 INFO - PROCESS | 1652 | |1;audio-beat-detection;147;114;143;144;137;136;146;142;152;113 08:45:32 INFO - PROCESS | 1652 | |2;audio-dft;214;267;285;229;199;204;198;200;195;202 08:45:32 INFO - PROCESS | 1652 | |3;audio-fft;107;51;50;106;113;84;77;59;80;82 08:45:32 INFO - PROCESS | 1652 | |4;audio-oscillator;87;70;87;72;69;71;86;86;85;106 08:45:32 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;115;118;130;119;115;114;167;170;121;118 08:45:32 INFO - PROCESS | 1652 | |6;imaging-darkroom;97;103;152;152;98;98;101;122;134;104 08:45:32 INFO - PROCESS | 1652 | |7;imaging-desaturate;94;105;92;91;96;92;91;94;94;92 08:45:32 INFO - PROCESS | 1652 | |8;json-parse-financial;60;58;58;57;58;60;60;60;61;68 08:45:32 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;69;68;71;105;112;110;82;73;72;71 08:45:32 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;89;89;90;89;92;90;88;88;93;100 08:45:32 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;183;159;151;142;149;153;155;141;211;165 08:45:32 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;173;176;173;177;182;178;178;180;177;181 08:45:32 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;67;75;70;72;67;67;69;67;67;68 08:45:32 INFO - PROCESS | 1652 | __end_tp_report 08:45:32 INFO - PROCESS | 1652 | __start_cc_report 08:45:32 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,953 08:45:32 INFO - PROCESS | 1652 | __end_cc_report 08:45:32 INFO - PROCESS | 1652 | __startTimestamp1481906732067__endTimestamp 08:45:32 INFO - PROCESS | 1652 | 08:45:32 INFO - PROCESS | 1652 | ------- Summary: start ------- 08:45:32 INFO - PROCESS | 1652 | Number of tests: 14 08:45:32 INFO - PROCESS | 1652 | 08:45:32 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:118.20 Median:119.00 stddev:4.49 (3.8%) stddev-sans-first:1.42 08:45:32 INFO - PROCESS | 1652 | Values: 106.0 119.0 119.0 122.0 118.0 118.0 121.0 119.0 121.0 119.0 08:45:32 INFO - PROCESS | 1652 | 08:45:32 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:143.50 stddev:13.42 (9.4%) stddev-sans-first:13.77 08:45:32 INFO - PROCESS | 1652 | Values: 147.0 114.0 143.0 144.0 137.0 136.0 146.0 142.0 152.0 113.0 08:45:32 INFO - PROCESS | 1652 | 08:45:32 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:219.30 Median:209.00 stddev:31.76 (15.2%) stddev-sans-first:33.62 08:45:32 INFO - PROCESS | 1652 | Values: 214.0 267.0 285.0 229.0 199.0 204.0 198.0 200.0 195.0 202.0 08:45:32 INFO - PROCESS | 1652 | 08:45:32 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:80.90 Median:68.00 stddev:22.84 (33.6%) stddev-sans-first:22.19 08:45:32 INFO - PROCESS | 1652 | Values: 107.0 51.0 50.0 106.0 113.0 84.0 77.0 59.0 80.0 82.0 08:45:32 INFO - PROCESS | 1652 | 08:45:32 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:81.90 Median:85.50 stddev:11.55 (13.5%) stddev-sans-first:12.10 08:45:32 INFO - PROCESS | 1652 | Values: 87.0 70.0 87.0 72.0 69.0 71.0 86.0 86.0 85.0 106.0 08:45:32 INFO - PROCESS | 1652 | 08:45:32 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:128.70 Median:120.00 stddev:21.47 (17.9%) stddev-sans-first:22.19 08:45:32 INFO - PROCESS | 1652 | Values: 115.0 118.0 130.0 119.0 115.0 114.0 167.0 170.0 121.0 118.0 08:45:32 INFO - PROCESS | 1652 | 08:45:32 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:116.10 Median:152.00 stddev:22.36 (14.7%) stddev-sans-first:22.62 08:45:32 INFO - PROCESS | 1652 | Values: 97.0 103.0 152.0 152.0 98.0 98.0 101.0 122.0 134.0 104.0 08:45:32 INFO - PROCESS | 1652 | 08:45:32 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:93.00 stddev:4.15 (4.5%) stddev-sans-first:4.40 08:45:32 INFO - PROCESS | 1652 | Values: 94.0 105.0 92.0 91.0 96.0 92.0 91.0 94.0 94.0 92.0 08:45:32 INFO - PROCESS | 1652 | 08:45:32 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:60.00 Median:60.00 stddev:3.09 (5.2%) stddev-sans-first:3.28 08:45:32 INFO - PROCESS | 1652 | Values: 60.0 58.0 58.0 57.0 58.0 60.0 60.0 60.0 61.0 68.0 08:45:32 INFO - PROCESS | 1652 | 08:45:32 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:83.30 Median:71.00 stddev:18.21 (25.6%) stddev-sans-first:18.56 08:45:32 INFO - PROCESS | 1652 | Values: 69.0 68.0 71.0 105.0 112.0 110.0 82.0 73.0 72.0 71.0 08:45:32 INFO - PROCESS | 1652 | 08:45:32 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:90.80 Median:89.50 stddev:3.61 (4.0%) stddev-sans-first:3.77 08:45:32 INFO - PROCESS | 1652 | Values: 89.0 89.0 90.0 89.0 92.0 90.0 88.0 88.0 93.0 100.0 08:45:32 INFO - PROCESS | 1652 | 08:45:32 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:160.90 Median:157.00 stddev:21.37 (13.6%) stddev-sans-first:21.11 08:45:32 INFO - PROCESS | 1652 | Values: 183.0 159.0 151.0 142.0 149.0 153.0 155.0 141.0 211.0 165.0 08:45:32 INFO - PROCESS | 1652 | 08:45:32 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.50 Median:178.00 stddev:3.03 (1.7%) stddev-sans-first:2.74 08:45:32 INFO - PROCESS | 1652 | Values: 173.0 176.0 173.0 177.0 182.0 178.0 178.0 180.0 177.0 181.0 08:45:32 INFO - PROCESS | 1652 | 08:45:32 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:68.50 stddev:2.73 (4.0%) stddev-sans-first:2.80 08:45:32 INFO - PROCESS | 1652 | Values: 67.0 75.0 70.0 72.0 67.0 67.0 69.0 67.0 67.0 68.0 08:45:32 INFO - PROCESS | 1652 | -------- Summary: end -------- 08:45:32 INFO - PROCESS | 1652 | 08:45:32 INFO - TEST-INFO | 1652: exit 0 08:45:32 INFO - TEST-OK | kraken | took 62181ms 08:45:32 INFO - SUITE-END | took 1178s 08:45:33 INFO - Completed test suite (00:19:38) 08:45:33 INFO - javascript benchmark 08:45:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7069.378773760179, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2953.6603290621574, 3348.229907121362, 3359.059463350263, 3368.1747971746927, 3387.3056023515182, 5570.855534933843, 5634.9154762678545, 5640.576956728247, 5682.971585142069, 5743.10981797822, 5524.917126243099, 5639.68981706006, 5654.830355089343, 5678.921005009045, 5728.79949201778, 5153.149730294506, 5159.251908473288, 5175.378954525443, 5193.896122077556, 5246.763895624687, 5232.8691782705355, 5242.606804489678, 5327.413984461706, 5327.627066105374, 5350.759215835296, 4390.209762242795, 5290.021346050974, 5292.444293349201, 5293.126634105368, 5297.655652382583, 274.15106849990633, 5148.845534633967, 5155.0, 5236.345456817898, 5337.5463085637575, 2860.699626539204, 5164.992826398854, 5172.1207394742605, 5195.116830138848, 5233.11037123686, 4252.617264446215, 5392.110301800199, 5627.521660658808, 5649.604527683064, 5669.659820410748, 5291.576673866081, 5295.364556253275, 5451.781928722847, 5456.91814622781, 6223.190985171898, 4663.790129444184, 5125.256837758504, 5830.892130495193, 6164.537659675498, 6189.473894718954, 5810.970945145247, 5813.069908814654, 5817.301923769175, 5831.737571809279, 5848.0, 5702.201691763156, 6048.274207095089, 6192.380761923771, 6208.906866397007, 6218.9378106219265, 4803.759812009385, 6003.939960600425, 6100.0, 6122.66325352101, 6142.846428839315, 5006.1739812930455, 6116.244470650255, 6139.492138333381, 6139.8908086453375, 6143.419763699168, 5182.922256166161, 6163.599366041193, 6176.629402235881, 6194.628322300677, 6201.937980620226, 6122.367382025772, 6169.784057557964, 6205.565610407228, 6215.751369945242, 6221.782237621662, 4763.190257656207, 4798.832040878552, 4806.49531799163, 4825.95174048262, 4926.45808961014, 4279.101388708406, 4353.782310884443, 4362.367456718758, 4370.890727731832, 4423.4912985006495, 5641.774329026793, 5687.687177205211, 5721.170430287585, 5732.828015159468, 5853.912191317134, 6135.938640613536, 6156.8768624627255, 6178.969105154535, 6192.349803270682, 6198.535109866688, 5626.465485778845, 5651.180578816049, 5659.801906933238, 5829.766809327664, 5859.589828711949, 5343.465653434625, 5366.973165134149, 5370.3287089113865, 5391.838244852583, 5416.377116631637, 6267.6866156691985, 6269.592476489013, 6284.308726040041, 6293.276273228637, 6317.7157027932935, 6156.5998210116195, 6172.043333283348, 6180.7527698891545, 6186.381361863958, 6188.195534580475, 6102.7619317136805, 6116.761878222105, 6174.567780255339, 6201.503879689524, 6269.529785266214, 5377.300950876361, 6114.357992410733, 6117.602355846856, 6128.503123582287, 6184.5052395809125, 6018.398160183947, 6110.114033465212, 6168.444839964424, 6180.845478863064, 6199.845003874939, 5931.733072011856, 5959.433853783884, 5976.820695379146, 6110.541709371903, 6154.261488621394, 6023.066424704178, 6039.849003774941, 6058.485028772604, 6065.3934606539, 6066.878662426639, 5856.853578660568, 5882.882342353043, 5964.045752679551, 6013.128096426081, 6023.969880150571, 6026.035834266497, 6107.236595425572, 6112.419320164578, 6122.0, 6143.662098584442, 4863.95136048635, 4918.262260660929, 5255.580993131909, 5853.824385268278, 5987.6108052976415], "value": 5525.021778451052, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.209391471011, 455.21475454840385, 487.461355202501, 487.8780304923769, 499.6802046690119, 35947.46078808817, 38038.23923521531, 38091.0, 38116.99999999996, 38198.0, 39593.60406395935, 39989.60010399895, 40077.19845603086, 40154.79922600383, 40265.396019059735, 8302.211289927447, 8550.957245213796, 9232.446053236794, 9467.005964373744, 9488.478133702643, 11028.0, 11318.490667919963, 11347.035501982342, 11471.139664525152, 11478.655640330804, 11079.833802492929, 11238.269512481704, 11253.493592788343, 11322.60370887019, 11331.886681133206, 11175.0, 11222.438878056064, 11228.775424491547, 11245.15661325404, 11254.212205145643, 11021.614243501519, 11037.227394082418, 11124.221304508688, 11190.720231994264, 11222.158338124673, 37751.43372849409, 37790.0, 38148.42777358342, 38191.0, 38325.80837095824, 3287.7862938909134, 3345.548350972612, 3396.9830150849334, 3468.8612455501748, 3700.85196592136, 24046.196535259925, 28721.425571488664, 29416.0, 29648.258793530335, 29816.8509157455, 3074.415860986428, 3145.308032232928, 3156.542301366312, 3185.0, 3252.7723059385853, 446504.0, 452090.0, 452320.0, 453225.0, 453368.0, 44107.77946110313, 54926.45073549214, 55167.72416137974, 55313.89372212533, 55318.72340638271, 57776.0, 57956.0, 58291.417085829446, 58334.416655833746, 58378.7081064592, 14350.49773257931, 15081.095134291758, 15341.616459588377, 15524.534263972098, 15534.378624854879, 10937.726556836144, 10997.780044399067, 11293.604723834791, 11980.0, 12235.143539952296, 14778.704425911421, 14930.850691492946, 14975.400983960737, 15041.247937603077, 15138.27511048022, 7116.0, 7176.928230717626, 7196.373323736799, 7222.313880181375, 7234.974207221986, 875.7022612311845, 1023.6263763726187, 1041.906228439429, 1092.7814437112609, 1157.774234024374, 28745.137645870658, 29101.563476547868, 29186.0, 29390.0, 29626.259343516584, 826.4545400036068, 916.6929078758691, 1018.0481250031245, 1031.7369070886875, 1120.7478317378525, 14808.185549794865, 14947.0, 15093.320800564, 15158.16630085356, 15274.847251527342, 553939.0, 763080.0, 773390.0, 776945.0, 781850.0, 49016.50983490191, 49747.75630609191, 49794.50205497971, 49888.0, 50233.0, 1345.8586848380976, 1366.152985149194, 1370.4244217428704, 1378.5726424808342, 1386.7989141574415, 25597.488050239263, 26101.08646197374, 27231.45537089287, 27621.44757104887, 27855.771538277295, 42754.78622606867, 42966.57033429626, 43000.13999720051, 43194.784026079666, 43413.224545300625, 530347.0, 542107.0, 544769.0, 544981.0, 547191.0, 22992.4251893704, 23076.076956921534, 23760.881195593913, 23934.04263829427, 24482.877585611957, 33609.26986793002, 39755.64096946969, 39800.60199397969, 39948.0, 40742.79628601838, 29384.412311753218, 32414.513782293758, 33319.33361332808, 33863.153421164665, 34376.31247374989, 26846.865765671046, 33639.66360336365, 33698.15754606154, 34550.0, 34768.30463390768, 32809.17977050593, 33508.8271910482, 33549.16127096842, 33830.0, 34067.82966085154, 28384.85807570949, 30049.0, 30217.848910755307, 30572.0, 30656.0, 22771.772282276965, 23267.650985234897, 23472.295831125095, 23506.88246558756, 23557.52884942326], "value": 18965.46928419962, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.73860911270987, 369.96855267302277, 374.17494424793335, 388.63080073929774, 400.507375927609, 18041.458756237316, 18577.814221857778, 19652.90173549132, 19656.508587285323, 19855.602887942234, 14012.509562165325, 19709.50726231845, 20415.0, 20686.069126889288, 20716.8605726685, 5599.832005039855, 5794.188813566104, 5829.854253643672, 5830.912536311958, 5831.416858314177, 5387.973060134694, 5662.8301150965535, 5733.197352370671, 5733.827985160452, 5767.134929760548, 5613.821097569495, 5693.28833895763, 5695.971520142393, 5700.030994730885, 5701.315842098933, 5532.1425179097305, 5670.773169073233, 5692.77228910843, 5716.256886604715, 5717.599768016241, 4920.3111564381015, 5033.219850923113, 5042.218456139304, 5051.444341122474, 5052.065367906949, 10952.34285942846, 12529.686757831036, 13041.804372934414, 13097.869021309856, 13109.278989655566, 2229.2197730794255, 2238.6194346960892, 2281.0305620111385, 2392.2823153054187, 2555.2845203343095, 9080.273578113734, 9464.763380915532, 9846.0, 10014.849777253346, 10051.24615653829, 1639.5737108351796, 1732.0300631646192, 1735.2191513818832, 1773.9822601774076, 1784.518180091368, 97814.0, 101017.99999999926, 101064.0, 101921.49039254831, 102205.48897255467, 11209.159313051554, 11236.381998990135, 11431.256968297033, 11459.140564457532, 11654.242474239147, 14297.285135743172, 14315.928420357832, 14318.498852540111, 14342.641433964025, 14345.282735863166, 8092.878606820902, 8284.0, 8328.708495202638, 8403.117672644406, 8435.915640843636, 8145.674173033012, 8163.224493673091, 8195.67217311301, 8299.419040667095, 8333.0, 4371.213181627338, 4509.774511274423, 4514.480834703985, 4543.341215523797, 4580.954190458119, 1654.4044144108111, 1890.4990177602947, 1936.7772706138692, 1942.339604534465, 1943.319838056669, 824.3281725393784, 869.8390797702446, 897.6768363389175, 962.0908241711516, 964.4358050540512, 16246.0, 19996.0, 20631.65894216871, 20635.69046464304, 20983.580328393346, 868.6568805321863, 907.1880666803306, 933.2114363362947, 955.3408148377597, 968.6416026070258, 9598.280128990353, 9743.415395076318, 10801.621943231949, 11262.887371126348, 11296.887031129583, 384969.0, 530571.0, 542731.0, 543678.0, 547355.0, 105759.94240057655, 106711.0, 110572.0, 125481.0, 126848.0, 946.7349142240183, 1000.1898462245605, 1016.364772017489, 1021.5862575656871, 1022.4693882640405, 15743.13412762309, 18905.77930664197, 18998.335058272893, 19017.714734279274, 19113.23547058102, 44204.77897610491, 44336.77831610821, 49881.50118498768, 51153.488465114875, 54165.45834541604, 59389.406105940114, 76577.31979380695, 78264.60867695625, 78807.60596197212, 79264.20735792568, 7645.923540764521, 7892.210778922292, 8017.518948863087, 8070.0, 8351.540665263468, 10531.789364212826, 12202.267863928193, 12310.569130080405, 12540.623781286577, 12818.294993775431, 10693.839592405964, 10976.12191024732, 11094.0, 11120.61077862271, 11178.10575154002, 10391.48042597852, 10783.6225732099, 10888.29226100301, 11102.888971110186, 11246.83129753038, 8904.86642700373, 9360.063993600585, 9722.125008749246, 9779.804403912025, 10988.890111099106, 7094.290570942864, 7644.159142494433, 7878.803029924297, 8278.130796266305, 8284.212999765012, 10633.734156646145, 10896.782064358827, 10908.018278354986, 11231.887681123411, 11237.38194399316], "value": 8998.411692142488, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1166.428450059471, 1471.8454562270958, 1610.1868556379027, 1804.657115148121, 1838.8620853435987, 6690.8291049066465, 7566.924330756705, 7826.8043298917555, 8218.301444377228, 8600.784980375485, 7042.929570704291, 8068.0, 8365.91634083659, 8465.153484651548, 8473.025602055766, 2279.5896738587007, 2321.7562155973633, 2331.0, 2333.3583264602203, 2337.392278007722, 2339.029302839328, 2349.01341436597, 2355.9057637694473, 2361.681173041635, 2375.5130198309307, 2299.7585253548386, 2322.256877799105, 2340.6840076589615, 2342.437814924423, 2350.7766762157567, 2291.3126062181364, 2297.5519773644146, 2314.7685231476717, 2319.118734880743, 2336.8714720690355, 2297.5060362022245, 2315.3285547191294, 2345.683332750091, 2356.516914032636, 2358.1982126077046, 8404.117567655368, 8433.072362040171, 8491.95754021226, 8503.0, 8507.787305317355, 5074.517920797555, 5209.557187639055, 5231.058409486291, 5238.397584277819, 5239.580833533309, 3201.27971206479, 3312.403767321905, 3449.4998225257195, 3467.6705712957482, 3470.7396945229207, 963.4460185393427, 1017.2574020964663, 1023.5240613114937, 1039.2465462539674, 1043.2175868098925, 10066.446345450924, 10911.508982095826, 10915.235933484659, 10960.958708922639, 11056.944715276453, 4429.136318417877, 4484.260097083945, 4632.86101416958, 4697.295405689174, 4710.623150148049, 9304.116108969638, 9417.670381536751, 9419.387739796892, 9431.858522122173, 9433.198178154797, 4400.471943366816, 5122.743862806845, 5226.163813789775, 5234.241035049897, 5236.345456817898, 4055.351143817034, 4286.892827679333, 4370.494942429565, 4749.691270067435, 4781.497942716034, 3787.9242415151543, 3845.5193100862393, 3888.5528164260904, 3909.8045097745, 3932.9606703932595, 1276.0812215205037, 1279.6864768131868, 1289.3231053696697, 1294.5339677716015, 1297.2216669998115, 600.1957377114687, 701.0, 885.5041176940933, 894.2845723421237, 920.3557509743207, 5501.972490137524, 5588.0, 5611.607187496836, 5652.8869422611315, 5709.800156994485, 754.4718696912183, 910.8406028944908, 915.2632131134501, 940.1115945431638, 956.8038552096804, 3776.86780962665, 3790.6967442604528, 3820.3696390095324, 3860.478835357247, 3932.410138479194, 8168.2240187183315, 9893.851592226123, 10151.441670708035, 10260.486975651187, 10453.634122805664, 9990.200783937267, 10212.131968782736, 10230.693079207636, 10274.743131421774, 10311.639092631873, 6810.842156833351, 7386.96306518464, 7739.264769846855, 7764.145943946161, 7909.841803164019, 5728.656280623176, 5816.651000939957, 5878.500327472213, 5931.495822855106, 5955.880882382415, 8911.197992180734, 8986.820263594822, 9082.954585227297, 9383.671571494964, 9458.574364153492, 9441.763955900882, 9467.147956683932, 9562.474063926551, 9564.378315409476, 9688.41869487833, 6630.801075967729, 6805.285445028201, 7131.001659767533, 7198.172210195895, 7380.409567234501, 7911.485753426009, 8081.111077781322, 8090.150534193825, 8108.878366824384, 8112.75661730149, 7174.8565028698085, 7270.418366530559, 7326.931288916246, 7366.447516436181, 7399.667014984447, 7048.330408611174, 7092.361687448154, 7130.500864939404, 7247.927520724726, 7280.745173918887, 6618.801435956929, 6780.627065511247, 6787.592744435349, 6798.0, 6817.318268173143, 4594.47163576193, 4829.468758436639, 4915.21356582945, 4948.628852836124, 5003.074431230234, 4451.020775429401, 4867.634927380532, 4942.258661200849, 4997.9500204997485, 5019.84940451787], "value": 4404.7094915795, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.90928358626763, 352.3235388054934, 353.2264341093006, 362.9038304849215, 369.5842177550254, 4572.051027087535, 5000.274960130783, 5199.402068762093, 5366.9463305366935, 5485.451454854512, 5130.897382052366, 5144.202648589475, 5159.910236354273, 5338.131653921126, 5385.180263664019, 3322.784019038767, 3331.083951913219, 3530.3115892400992, 3919.9804000979957, 4221.535631080586, 3507.1582820123112, 4241.5122260939925, 4262.147570485899, 4278.208531421681, 4284.6786491013145, 3500.982495087521, 4024.859129930453, 4040.2121586290687, 4046.433499310099, 4071.3282308419075, 3698.4267438546935, 4015.112896938449, 4024.3561030235023, 4048.5344185418758, 4068.9389659155136, 2936.6769655337894, 3337.2658015236607, 3491.738119641037, 3498.5276987606603, 3504.229069604683, 4948.7278199699, 5228.137357336035, 5277.155655095166, 5281.947180528184, 5323.467653234684, 4112.403701463271, 4214.978925105354, 4605.884852878671, 4673.018666080127, 4674.11191873543, 5480.506754392084, 5738.655680659173, 5755.597108202427, 5778.913316300259, 5783.392743761929, 3611.8916432507067, 3794.8671796487265, 3844.3464611016193, 3895.2014836958356, 3911.3546264866263, 4895.804167833282, 5283.973580132075, 5703.43345494525, 5703.971480142573, 5979.760809567612, 3328.900132996014, 3900.3759398496104, 3911.7923302891354, 3936.488256526633, 3936.618345900746, 3310.9570485297054, 3980.482537270136, 3988.3818008208773, 3990.1221731219084, 3996.2606917720314, 4175.436316097349, 4453.153900758845, 4506.7070640408265, 4627.7143228307605, 4692.41344831896, 5795.478406943395, 5805.448482394166, 5850.61970971894, 5860.560457965669, 5940.970295148497, 1161.372858656316, 1167.9240849344765, 1214.9794172894635, 1225.7548490301974, 1283.8908692761033, 848.8047749017637, 863.8617821148712, 870.2646263906988, 873.5588527793424, 876.7287433221854, 4817.445993710698, 5145.614078944094, 5157.575939308519, 5173.534381905646, 5191.844244672666, 4721.834735784231, 4909.288153217834, 5577.358603760538, 5682.232898558725, 5728.742206600713, 3956.188981258835, 4339.327404252346, 4956.553910148104, 5100.897982040413, 5125.205593133071, 2526.0527302261653, 2625.647791387437, 2786.958195627067, 2870.942581148365, 2995.266159790865, 5342.515730185482, 5918.733656985446, 5928.79249226275, 5959.960420185736, 6040.335563088057, 5109.74451277442, 5200.76596553156, 5340.184337325328, 5488.835334939959, 5689.971550142222, 4764.689125773997, 5124.769385377667, 5249.658772179872, 5475.863103422366, 5695.800646977336, 5284.471552844839, 5295.319752771787, 5356.9286142770825, 5553.778168802857, 5719.139580783038, 4880.170371036861, 5356.5714742821265, 5563.720344320748, 5881.617694849821, 5922.703864806828, 4843.539863712942, 4900.632452566, 6009.038553831366, 6194.938050619436, 6200.472959798468, 3264.8452021665585, 3966.781826999543, 3974.006498375406, 3982.7012974027643, 4009.6391324779743, 4581.5189405113315, 5141.0, 5412.161115027177, 5904.5866789326055, 5958.553108516792, 4806.663533552618, 5393.784248630168, 5795.797147099831, 5811.709414529172, 5821.970890145522, 5134.640575159703, 5667.0, 5824.301083869963, 5831.970840145772, 5845.327787304344, 5107.876267221205, 5315.707636080053, 5417.914103070268, 5594.4125866785025, 5709.885802284014, 5686.658800471985, 5686.905428091767, 5714.571407144397, 5717.971410142923, 5728.3985181557, 5188.377394712658, 5450.937067271844, 5604.747786349542, 5643.209950607001, 5678.403767604342], "value": 4075.9297998740353, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.7819914925947, 723.4790950515627, 724.855028994201, 740.0231694163706, 752.0, 14266.0, 15206.847931520682, 15271.923640381783, 15320.693586128285, 15324.540263792076, 13117.737645247089, 13786.517471888486, 14018.369173387196, 14050.508232211874, 14098.506552270672, 847.2332539052168, 867.9305655547572, 872.520113937335, 873.0352959979227, 875.0330884372772, 685.4721864164591, 707.2891743797476, 842.8609279468911, 859.6733241368271, 865.4720620421538, 893.4505279253245, 916.083916083916, 917.0920788419484, 919.7746552094746, 920.8296465153968, 901.2699713232303, 907.9092090790934, 908.900020997693, 909.8316811389914, 911.3893691226893, 663.9468842492589, 669.3674477618653, 675.9898601520981, 677.2584020497547, 678.2505331608551, 12243.449044793006, 12599.874001260054, 15265.236738163047, 15293.464728734552, 15398.923005385013, 14182.361793719307, 14416.207108609022, 14588.854111458855, 14737.852621473756, 14781.33483993223, 10182.338148020355, 10633.308834925705, 11201.775964480747, 11242.437878106062, 11396.430178491042, 3156.8421578921193, 3655.067957670777, 3658.817059147032, 3676.485292059114, 3715.777053376806, 110180.0, 110270.44864775705, 117158.0, 119750.40124799407, 120288.39855800752, 10737.83893241602, 10969.50637221311, 11044.061254793272, 11056.778864422666, 11300.095992320594, 23227.419314516934, 23483.88258058699, 23554.88222558876, 23688.881555592456, 23693.644595331083, 7819.452638315377, 7864.567448790372, 7895.44089814602, 7896.486728362638, 7905.683772649029, 4225.830966761357, 4316.589923957219, 4393.714408563433, 4427.26950053244, 4469.843555475542, 3295.6374798772117, 3325.4961345341208, 3447.5345828313357, 3540.4689296605716, 3564.697000754965, 627.6046090962665, 639.3957709964039, 651.739304278283, 654.0385633119307, 662.0499583098269, 610.6183635227983, 651.8989556618732, 657.3853447027045, 668.7127279986007, 685.6194811879454, 7780.416468764865, 7835.373170146375, 8975.910240897638, 9048.321375896834, 9149.54252287383, 488.93154958305865, 537.1164434505217, 572.7279542217414, 672.2470832667444, 672.8116127484312, 4334.133173365277, 4431.158079964861, 4511.774411279423, 4615.423072115986, 4711.552402521755, 100791.49604251931, 102375.4881225589, 104757.0, 105327.0, 110006.0, 87295.56352218325, 92728.53635731777, 93596.0, 93980.53009734908, 96412.0, 60489.395106048374, 61343.38656613377, 67362.98955515672, 71832.0, 72599.63700181466, 9375.390599611004, 9776.693470088818, 9862.901370986197, 9955.502224888582, 9958.253131015057, 36810.079748006516, 44849.55150448454, 47396.7630161847, 49619.778499160406, 51102.48897511126, 62765.0, 67454.9881751784, 67823.98264025945, 68138.63722725617, 68526.65736671285, 12510.061745369316, 12841.229526228457, 12894.67763305925, 13096.672583185496, 13416.32918354098, 13386.661333866536, 13564.93217533906, 14231.644208894446, 14386.208758518382, 14771.556853294418, 13435.328233588478, 13442.059055865995, 13527.729445411234, 13611.387487563281, 14054.578362649137, 13400.99492538044, 13604.387802549107, 13640.590782276548, 13775.724485510033, 14074.718505629626, 11338.036266917074, 12912.0, 13179.802302965272, 13544.789125130394, 13853.37659805291, 10915.508802104083, 11215.88784112181, 11221.438928053727, 11378.655827958488, 11414.258073225214, 9843.557039933363, 11017.44912754375, 11171.608993685182, 11450.141239406912, 11460.770784584429], "value": 7372.841330409322, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1618.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 119.0, 122.0, 118.0, 118.0, 121.0, 119.0, 121.0, 119.0], "value": 118.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 114.0, 143.0, 144.0, 137.0, 136.0, 146.0, 142.0, 152.0, 113.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [214.0, 267.0, 285.0, 229.0, 199.0, 204.0, 198.0, 200.0, 195.0, 202.0], "value": 219.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 51.0, 50.0, 106.0, 113.0, 84.0, 77.0, 59.0, 80.0, 82.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 87.0, 72.0, 69.0, 71.0, 86.0, 86.0, 85.0, 106.0], "value": 81.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 118.0, 130.0, 119.0, 115.0, 114.0, 167.0, 170.0, 121.0, 118.0], "value": 128.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 103.0, 152.0, 152.0, 98.0, 98.0, 101.0, 122.0, 134.0, 104.0], "value": 116.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 105.0, 92.0, 91.0, 96.0, 92.0, 91.0, 94.0, 94.0, 92.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 58.0, 57.0, 58.0, 60.0, 60.0, 60.0, 61.0, 68.0], "value": 60.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 71.0, 105.0, 112.0, 110.0, 82.0, 73.0, 72.0, 71.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 90.0, 89.0, 92.0, 90.0, 88.0, 88.0, 93.0, 100.0], "value": 90.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 159.0, 151.0, 142.0, 149.0, 153.0, 155.0, 141.0, 211.0, 165.0], "value": 160.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 176.0, 173.0, 177.0, 182.0, 178.0, 178.0, 180.0, 177.0, 181.0], "value": 177.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 75.0, 70.0, 72.0, 67.0, 67.0, 69.0, 67.0, 67.0, 68.0], "value": 68.9, "unit": "score"}]}]} 08:45:33 INFO - Return code: 0 08:45:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:45:33 ERROR - # TBPL SUCCESS # 08:45:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:45:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:45:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:45:33 INFO - 2016-12-16 08:45:33.391 screenresolution[1657:10738] starting screenresolution argv=screenresolution get 08:45:33 INFO - 2016-12-16 08:45:33.394 screenresolution[1657:10738] Display 0: 1600x1200x32@60 08:45:33 INFO - 2016-12-16 08:45:33.400 screenresolution[1658:10741] starting screenresolution argv=screenresolution list 08:45:33 INFO - Available Modes on Display 0 08:45:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:45:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:45:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:45:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:45:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:45:33 INFO - Intel Iris: 08:45:33 INFO - Chipset Model: Intel Iris 08:45:33 INFO - Type: GPU 08:45:33 INFO - Bus: Built-In 08:45:33 INFO - VRAM (Dynamic, Max): 1536 MB 08:45:33 INFO - Vendor: Intel (0x8086) 08:45:33 INFO - Device ID: 0x0a2e 08:45:33 INFO - Revision ID: 0x0009 08:45:33 INFO - Displays: 08:45:33 INFO - Display: 08:45:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:45:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:45:33 INFO - Display Serial Number: 1600x1200 60 08:45:33 INFO - Main Display: Yes 08:45:33 INFO - Mirror: Off 08:45:33 INFO - Online: Yes 08:45:33 INFO - Rotation: Supported 08:45:33 INFO - Return code: 0 08:45:33 INFO - Running post-action listener: _resource_record_post_action 08:45:33 INFO - [mozharness: 2016-12-16 16:45:33.636196Z] Finished run-tests step (success) 08:45:33 INFO - Running post-run listener: _resource_record_post_run 08:45:33 INFO - Total resource usage - Wall time: 1202s; CPU: 55.0%; Read bytes: 34517504; Write bytes: 553834496; Read time: 395; Write time: 2187 08:45:33 INFO - TinderboxPrint: CPU usage
54.7% 08:45:33 INFO - TinderboxPrint: I/O read bytes / time
34,517,504 / 395 08:45:33 INFO - TinderboxPrint: I/O write bytes / time
553,834,496 / 2,187 08:45:33 INFO - TinderboxPrint: CPU idle
2,180.6 (45.3%) 08:45:33 INFO - TinderboxPrint: CPU system
974.9 (20.3%) 08:45:33 INFO - TinderboxPrint: CPU user
1,654.3 (34.4%) 08:45:33 INFO - TinderboxPrint: Swap in / out
279,838,720 / 0 08:45:33 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 232917504; Write bytes: 225288192; Read time: 17574; Write time: 408 08:45:33 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 33587712; Write bytes: 303532032; Read time: 377; Write time: 1730 08:45:34 INFO - Running post-run listener: _upload_blobber_files 08:45:34 INFO - Blob upload gear active. 08:45:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:45:34 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 08:45:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:45:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:45:34 INFO - (blobuploader) - INFO - Open directory for files ... 08:45:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:45:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:45:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:35 INFO - (blobuploader) - INFO - Done attempting. 08:45:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:45:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:45:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:36 INFO - (blobuploader) - INFO - Done attempting. 08:45:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:45:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:45:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:38 INFO - (blobuploader) - INFO - Done attempting. 08:45:38 INFO - (blobuploader) - INFO - Iteration through files over. 08:45:38 INFO - Return code: 0 08:45:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:45:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:45:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de0ff2bab7276e280af8e66c92a02fb46adcf39f5b9e9a5857bbe010430c2dbe24403455be01d1d450a70aed5b0e938f7a7277c99fb30cfe3c269487a0981a25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc7ab113379e9c858f67324f0304b307ef040375b1fc30058238b1cd8c9290f9d8b5f3e3e368c8a95dee37f91e37c965bf2409af8b72eb12b3cf21a59e70c618", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc251730df239e1e8575876c4167c4fcc069e3354e71ff67e2c73eebb0604d78455ab8da3880584082835b101477d62f228d9fdd54cc68af48f45e5a921b91c0"} 08:45:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:45:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:45:38 INFO - Contents: 08:45:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de0ff2bab7276e280af8e66c92a02fb46adcf39f5b9e9a5857bbe010430c2dbe24403455be01d1d450a70aed5b0e938f7a7277c99fb30cfe3c269487a0981a25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc7ab113379e9c858f67324f0304b307ef040375b1fc30058238b1cd8c9290f9d8b5f3e3e368c8a95dee37f91e37c965bf2409af8b72eb12b3cf21a59e70c618", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc251730df239e1e8575876c4167c4fcc069e3354e71ff67e2c73eebb0604d78455ab8da3880584082835b101477d62f228d9fdd54cc68af48f45e5a921b91c0"} 08:45:38 INFO - Running post-run listener: copy_logs_to_upload_dir 08:45:38 INFO - Copying logs to upload dir... 08:45:38 INFO - mkdir: /builds/slave/test/build/upload/logs 08:45:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.721439 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-12-16 08:45:38.572557) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 08:45:38.576310) ========= 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.mBwJ9nndGN/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.u3ZZfBoKlW/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de0ff2bab7276e280af8e66c92a02fb46adcf39f5b9e9a5857bbe010430c2dbe24403455be01d1d450a70aed5b0e938f7a7277c99fb30cfe3c269487a0981a25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc7ab113379e9c858f67324f0304b307ef040375b1fc30058238b1cd8c9290f9d8b5f3e3e368c8a95dee37f91e37c965bf2409af8b72eb12b3cf21a59e70c618", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc251730df239e1e8575876c4167c4fcc069e3354e71ff67e2c73eebb0604d78455ab8da3880584082835b101477d62f228d9fdd54cc68af48f45e5a921b91c0"} build_url:https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010330 build_url: 'https://queue.taskcluster.net/v1/task/VsIWB3KuQwKcDYd58WfXIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de0ff2bab7276e280af8e66c92a02fb46adcf39f5b9e9a5857bbe010430c2dbe24403455be01d1d450a70aed5b0e938f7a7277c99fb30cfe3c269487a0981a25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc7ab113379e9c858f67324f0304b307ef040375b1fc30058238b1cd8c9290f9d8b5f3e3e368c8a95dee37f91e37c965bf2409af8b72eb12b3cf21a59e70c618", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc251730df239e1e8575876c4167c4fcc069e3354e71ff67e2c73eebb0604d78455ab8da3880584082835b101477d62f228d9fdd54cc68af48f45e5a921b91c0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 08:45:38.614124) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:45:38.614456) ========= 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.mBwJ9nndGN/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.u3ZZfBoKlW/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.004839 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:45:38.663627) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 08:45:38.663942) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 08:45:38.664253) ========= ========= Total master_lag: 0.18 =========