builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0021 starttime: 1483273418.89 results: success (0) buildid: 20170101030204 builduid: 543fbffa71b84d18bae0a55495483a61 revision: cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-01 04:23:38.894993) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-01 04:23:38.895397) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-01 04:23:38.895668) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-01 04:23:38.922235) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 04:23:38.922613) ========= 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.KnjH3M5z9q/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.7bzrk0XQ7d/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 --2017-01-01 04:23:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2017-01-01 04:23:39 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.505862 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 04:23:39.472554) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 04:23:39.472880) ========= 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.KnjH3M5z9q/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.7bzrk0XQ7d/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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.028345 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 04:23:39.529249) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 04:23:39.529613) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 --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 cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KnjH3M5z9q/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.7bzrk0XQ7d/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 2017-01-01 04:23:39,633 truncating revision to first 12 chars 2017-01-01 04:23:39,633 Setting DEBUG logging. 2017-01-01 04:23:39,633 attempt 1/10 2017-01-01 04:23:39,633 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cb9f43eb5525?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-01 04:23:40,281 unpacking tar archive at: mozilla-central-cb9f43eb5525/testing/mozharness/ program finished with exit code 0 elapsedTime=0.929866 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 04:23:40.487218) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-01 04:23:40.487502) ========= script_repo_revision: cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-01 04:23:40.487860) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-01 04:23:40.488128) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-01 04:23:40.537566) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-01 04:23:40.537833) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KnjH3M5z9q/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.7bzrk0XQ7d/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:23:40 INFO - MultiFileLogger online at 20170101 04:23:40 in /builds/slave/test 04:23:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:23:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:23:40 INFO - {'append_to_log': False, 04:23:40 INFO - 'base_work_dir': '/builds/slave/test', 04:23:40 INFO - 'blob_upload_branch': 'Firefox', 04:23:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:23:40 INFO - 'branch': 'Firefox', 04:23:40 INFO - 'buildbot_json_path': 'buildprops.json', 04:23:40 INFO - 'config_files': ('talos/mac_config.py',), 04:23:40 INFO - 'default_actions': ('clobber', 04:23:40 INFO - 'read-buildbot-config', 04:23:40 INFO - 'download-and-extract', 04:23:40 INFO - 'populate-webroot', 04:23:40 INFO - 'create-virtualenv', 04:23:40 INFO - 'install', 04:23:40 INFO - 'run-tests'), 04:23:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:23:40 INFO - 'download_minidump_stackwalk': True, 04:23:40 INFO - 'download_symbols': 'ondemand', 04:23:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:23:40 INFO - 'tooltool.py': '/tools/tooltool.py', 04:23:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:23:40 INFO - '/tools/misc-python/virtualenv.py')}, 04:23:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:23:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:23:40 INFO - 'installer_path': 'installer.exe', 04:23:40 INFO - 'log_level': 'info', 04:23:40 INFO - 'log_name': 'talos', 04:23:40 INFO - 'log_to_console': True, 04:23:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:23:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:23:40 INFO - 'opt_config_files': (), 04:23:40 INFO - 'pip_index': False, 04:23:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:23:40 INFO - 'cmd': ('bash', 04:23:40 INFO - '-c', 04:23:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:23:40 INFO - 'enabled': True, 04:23:40 INFO - 'halt_on_failure': False, 04:23:40 INFO - 'name': 'check_screen_resolution'},), 04:23:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:23:40 INFO - 'cmd': ('bash', 04:23:40 INFO - '-c', 04:23:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:23:40 INFO - 'enabled': True, 04:23:40 INFO - 'halt_on_failure': False, 04:23:40 INFO - 'name': 'check_screen_resolution'},), 04:23:40 INFO - 'run_cmd_checks_enabled': True, 04:23:40 INFO - 'sps_profile': False, 04:23:40 INFO - 'sps_profile_interval': 0, 04:23:40 INFO - 'suite': 'dromaeojs', 04:23:40 INFO - 'system_bits': '32', 04:23:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:23:40 INFO - 'title': 't-yosemite-r7-0021', 04:23:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:23:40 INFO - 'use_talos_json': True, 04:23:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:23:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:23:40 INFO - 'work_dir': 'build'} 04:23:40 INFO - [mozharness: 2017-01-01 12:23:40.748605Z] Running clobber step. 04:23:40 INFO - Running pre-action listener: _resource_record_pre_action 04:23:40 INFO - Running main action method: clobber 04:23:40 INFO - rmtree: /builds/slave/test/build 04:23:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:23:43 INFO - Running post-action listener: _resource_record_post_action 04:23:43 INFO - [mozharness: 2017-01-01 12:23:43.080916Z] Finished clobber step (success) 04:23:43 INFO - [mozharness: 2017-01-01 12:23:43.081056Z] Running read-buildbot-config step. 04:23:43 INFO - Running pre-action listener: _resource_record_pre_action 04:23:43 INFO - Running main action method: read_buildbot_config 04:23:43 INFO - Using buildbot properties: 04:23:43 INFO - { 04:23:43 INFO - "project": "", 04:23:43 INFO - "product": "firefox", 04:23:43 INFO - "script_repo_revision": "production", 04:23:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:23:43 INFO - "repository": "", 04:23:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:23:43 INFO - "buildid": "20170101030204", 04:23:43 INFO - "stage_platform": "macosx64", 04:23:43 INFO - "basedir": "/builds/slave/test", 04:23:43 INFO - "nightly_build": "True", 04:23:43 INFO - "buildnumber": 99, 04:23:43 INFO - "slavename": "t-yosemite-r7-0021", 04:23:43 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:23:43 INFO - "platform": "yosemite_r7", 04:23:43 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:23:43 INFO - "branch": "mozilla-central", 04:23:43 INFO - "repo_path": "mozilla-central", 04:23:43 INFO - "revision": "cb9f43eb552570e185e95dfc8acb9f9fbe47ae17", 04:23:43 INFO - "pgo_build": "False", 04:23:43 INFO - "builduid": "543fbffa71b84d18bae0a55495483a61", 04:23:43 INFO - "slavebuilddir": "test" 04:23:43 INFO - } 04:23:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:23:43 INFO - Running post-action listener: _resource_record_post_action 04:23:43 INFO - [mozharness: 2017-01-01 12:23:43.084542Z] Finished read-buildbot-config step (success) 04:23:43 INFO - [mozharness: 2017-01-01 12:23:43.084678Z] Running download-and-extract step. 04:23:43 INFO - Running pre-action listener: _resource_record_pre_action 04:23:43 INFO - Running main action method: download_and_extract 04:23:43 INFO - mkdir: /builds/slave/test/build/tests 04:23:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:43 INFO - trying https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:23:43 INFO - Downloading https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/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 04:23:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/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 04:23:44 INFO - Downloaded 1199 bytes. 04:23:44 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:23:44 INFO - Using the following test package requirements: 04:23:44 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:23:44 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:23:44 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:23:44 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:23:44 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:23:44 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:23:44 INFO - u'jsshell-mac.zip'], 04:23:44 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:23:44 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:23:44 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:23:44 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:23:44 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:23:44 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:23:44 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:23:44 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:23:44 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:23:44 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:23:44 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:23:44 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:23:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:23:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:23:44 INFO - Fetch https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:23:45 INFO - Expected file size: 16299851 04:23:45 INFO - Obtained file size: 16299851 04:23:52 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 04:23:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:23:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:23:52 INFO - Fetch https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:23:53 INFO - Expected file size: 16299851 04:23:53 INFO - Obtained file size: 16299851 04:23:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:23:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:23:59 INFO - Fetch https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:24:03 INFO - Expected file size: 14409776 04:24:03 INFO - Obtained file size: 14409776 04:24:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:03 INFO - trying https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:24:03 INFO - Downloading https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:24:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/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 04:24:05 INFO - Downloaded 69826203 bytes. 04:24:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:24:05 INFO - mkdir: /builds/slave/test/properties 04:24:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:24:05 INFO - Writing to file /builds/slave/test/properties/build_url 04:24:05 INFO - Contents: 04:24:05 INFO - build_url:https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:24:06 INFO - Running post-action listener: _resource_record_post_action 04:24:06 INFO - Running post-action listener: set_extra_try_arguments 04:24:06 INFO - [mozharness: 2017-01-01 12:24:06.721684Z] Finished download-and-extract step (success) 04:24:06 INFO - [mozharness: 2017-01-01 12:24:06.721818Z] Running populate-webroot step. 04:24:06 INFO - Running pre-action listener: _resource_record_pre_action 04:24:06 INFO - Running main action method: populate_webroot 04:24:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:24:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:24:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:24:06 INFO - '--tppagecycles', 04:24:06 INFO - '7'], 04:24:06 INFO - 'tests': ['tsvgm']}}, 04:24:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:24:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:24:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:24:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:24:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:24:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:24:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:24:06 INFO - 'talos_options': ['--disable-e10s'], 04:24:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:24:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:24:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:24:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:24:06 INFO - 'talos_options': ['--disable-e10s'], 04:24:06 INFO - 'tests': ['damp', 'tps']}, 04:24:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:24:06 INFO - 'tests': ['damp', 'tps']}, 04:24:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:24:06 INFO - 'tests': ['dromaeo_dom']}, 04:24:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:24:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:24:06 INFO - 'tests': ['basic_compositor_video']}, 04:24:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:24:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:24:06 INFO - 'tests': ['a11yr', 04:24:06 INFO - 'ts_paint', 04:24:06 INFO - 'tpaint', 04:24:06 INFO - 'sessionrestore', 04:24:06 INFO - 'sessionrestore_no_auto_restore', 04:24:06 INFO - 'tabpaint']}, 04:24:06 INFO - 'other-e10s': {'tests': ['a11yr', 04:24:06 INFO - 'ts_paint', 04:24:06 INFO - 'tpaint', 04:24:06 INFO - 'sessionrestore', 04:24:06 INFO - 'sessionrestore_no_auto_restore', 04:24:06 INFO - 'tabpaint']}, 04:24:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:24:06 INFO - 'ts_paint', 04:24:06 INFO - 'tpaint', 04:24:06 INFO - 'sessionrestore', 04:24:06 INFO - 'sessionrestore_no_auto_restore', 04:24:06 INFO - 'tabpaint']}, 04:24:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:24:06 INFO - 'ts_paint', 04:24:06 INFO - 'tpaint', 04:24:06 INFO - 'sessionrestore', 04:24:06 INFO - 'sessionrestore_no_auto_restore', 04:24:06 INFO - 'tabpaint']}, 04:24:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:24:06 INFO - 'tests': ['a11yr', 04:24:06 INFO - 'ts_paint', 04:24:06 INFO - 'tpaint', 04:24:06 INFO - 'sessionrestore', 04:24:06 INFO - 'sessionrestore_no_auto_restore', 04:24:06 INFO - 'tabpaint']}, 04:24:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:24:06 INFO - 'tests': ['a11yr', 04:24:06 INFO - 'ts_paint', 04:24:06 INFO - 'tpaint', 04:24:06 INFO - 'sessionrestore', 04:24:06 INFO - 'sessionrestore_no_auto_restore', 04:24:06 INFO - 'tabpaint']}, 04:24:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:24:06 INFO - 'tests': ['tsvgx', 04:24:06 INFO - 'tsvgr_opacity', 04:24:06 INFO - 'tart', 04:24:06 INFO - 'tscrollx', 04:24:06 INFO - 'cart', 04:24:06 INFO - 'tsvg_static']}, 04:24:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:24:06 INFO - 'tsvgr_opacity', 04:24:06 INFO - 'tart', 04:24:06 INFO - 'tscrollx', 04:24:06 INFO - 'cart', 04:24:06 INFO - 'tsvg_static']}, 04:24:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:24:06 INFO - 'talos_options': ['--disable-e10s'], 04:24:06 INFO - 'tests': ['tp5o']}, 04:24:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:24:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:24:06 INFO - 'talos_options': ['--disable-e10s', 04:24:06 INFO - '--xperf_path', 04:24:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:24:06 INFO - 'tests': ['tp5n']}, 04:24:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:24:06 INFO - 'talos_options': ['--xperf_path', 04:24:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:24:06 INFO - 'tests': ['tp5n']}}, 04:24:06 INFO - 'talos.zip': {'path': '', 04:24:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:24:06 INFO - Running post-action listener: _resource_record_post_action 04:24:06 INFO - [mozharness: 2017-01-01 12:24:06.740205Z] Finished populate-webroot step (success) 04:24:06 INFO - [mozharness: 2017-01-01 12:24:06.740321Z] Running create-virtualenv step. 04:24:06 INFO - Running pre-action listener: _resource_record_pre_action 04:24:06 INFO - Running main action method: create_virtualenv 04:24:06 INFO - Creating virtualenv /builds/slave/test/build/venv 04:24:06 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:24:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:24:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:24:06 INFO - Using real prefix '/tools/python27' 04:24:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:24:07 INFO - Installing distribute.............................................................................................................................................................................................done. 04:24:10 INFO - Installing pip.................done. 04:24:10 INFO - Return code: 0 04:24:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:24:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:24:10 INFO - Reading from file tmpfile_stdout 04:24:10 INFO - Output received: 04:24:10 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:24:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:24: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')]} 04:24:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24: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')]} 04:24:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b036b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1bbca5c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae14d30>, '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 0x10ad3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae09be8>, '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.KnjH3M5z9q/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.7bzrk0XQ7d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:24: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', 'pip>=1.5'] in /builds/slave/test/build 04:24: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 pip>=1.5 04:24:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnjH3M5z9q/Render', 04:24:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:10 INFO - 'HOME': '/Users/cltbld', 04:24:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:10 INFO - 'LOGNAME': 'cltbld', 04:24:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:10 INFO - 'MOZ_NO_REMOTE': '1', 04:24:10 INFO - 'NO_EM_RESTART': '1', 04:24:10 INFO - 'PAGER': '/bin/cat', 04:24:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:10 INFO - 'PWD': '/builds/slave/test', 04:24:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:10 INFO - 'SHELL': '/bin/bash', 04:24:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bzrk0XQ7d/Listeners', 04:24:10 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 04:24:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:10 INFO - 'USER': 'cltbld', 04:24:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:10 INFO - 'XPC_FLAGS': '0x0', 04:24:10 INFO - 'XPC_SERVICE_NAME': '0', 04:24:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:11 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:24:11 INFO - Cleaning up... 04:24:11 INFO - Return code: 0 04:24:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:24:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:11 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 0x10b036b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1bbca5c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae14d30>, '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 0x10ad3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae09be8>, '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.KnjH3M5z9q/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.7bzrk0XQ7d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:24:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:24:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:24:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnjH3M5z9q/Render', 04:24:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:11 INFO - 'HOME': '/Users/cltbld', 04:24:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:11 INFO - 'LOGNAME': 'cltbld', 04:24:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:11 INFO - 'MOZ_NO_REMOTE': '1', 04:24:11 INFO - 'NO_EM_RESTART': '1', 04:24:11 INFO - 'PAGER': '/bin/cat', 04:24:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:11 INFO - 'PWD': '/builds/slave/test', 04:24:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:11 INFO - 'SHELL': '/bin/bash', 04:24:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bzrk0XQ7d/Listeners', 04:24:11 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 04:24:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:11 INFO - 'USER': 'cltbld', 04:24:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:11 INFO - 'XPC_FLAGS': '0x0', 04:24:11 INFO - 'XPC_SERVICE_NAME': '0', 04:24:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:11 INFO - Downloading/unpacking psutil>=3.1.1 04:24: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 04:24: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 04:24: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 04:24: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 04:24: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 04:24: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 04:24:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:24:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:24:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:24:18 INFO - Installing collected packages: psutil 04:24:18 INFO - Running setup.py install for psutil 04:24:18 INFO - building 'psutil._psutil_osx' extension 04:24:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:24:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:24:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:24:18 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:24:19 INFO - building 'psutil._psutil_posix' extension 04:24:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:24:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:24:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:24:19 INFO - ^ 04:24:19 INFO - 1 warning generated. 04:24:19 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:24:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:24:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:24:19 INFO - Successfully installed psutil 04:24:19 INFO - Cleaning up... 04:24:19 INFO - Return code: 0 04:24:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:19 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 0x10b036b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1bbca5c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae14d30>, '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 0x10ad3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae09be8>, '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.KnjH3M5z9q/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.7bzrk0XQ7d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:24:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:24:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:24:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnjH3M5z9q/Render', 04:24:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:19 INFO - 'HOME': '/Users/cltbld', 04:24:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:19 INFO - 'LOGNAME': 'cltbld', 04:24:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:19 INFO - 'MOZ_NO_REMOTE': '1', 04:24:19 INFO - 'NO_EM_RESTART': '1', 04:24:19 INFO - 'PAGER': '/bin/cat', 04:24:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:19 INFO - 'PWD': '/builds/slave/test', 04:24:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:19 INFO - 'SHELL': '/bin/bash', 04:24:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bzrk0XQ7d/Listeners', 04:24:19 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 04:24:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:19 INFO - 'USER': 'cltbld', 04:24:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:19 INFO - 'XPC_FLAGS': '0x0', 04:24:19 INFO - 'XPC_SERVICE_NAME': '0', 04:24:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:24:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:24:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:24:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:24:23 INFO - Installing collected packages: mozsystemmonitor 04:24:23 INFO - Running setup.py install for mozsystemmonitor 04:24:23 INFO - Successfully installed mozsystemmonitor 04:24:23 INFO - Cleaning up... 04:24:23 INFO - Return code: 0 04:24:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:24:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:23 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 0x10b036b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1bbca5c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae14d30>, '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 0x10ad3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae09be8>, '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.KnjH3M5z9q/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.7bzrk0XQ7d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:24:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:24:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:24:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnjH3M5z9q/Render', 04:24:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:23 INFO - 'HOME': '/Users/cltbld', 04:24:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:23 INFO - 'LOGNAME': 'cltbld', 04:24:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:23 INFO - 'MOZ_NO_REMOTE': '1', 04:24:23 INFO - 'NO_EM_RESTART': '1', 04:24:23 INFO - 'PAGER': '/bin/cat', 04:24:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:23 INFO - 'PWD': '/builds/slave/test', 04:24:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:23 INFO - 'SHELL': '/bin/bash', 04:24:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bzrk0XQ7d/Listeners', 04:24:23 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 04:24:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:23 INFO - 'USER': 'cltbld', 04:24:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:23 INFO - 'XPC_FLAGS': '0x0', 04:24:23 INFO - 'XPC_SERVICE_NAME': '0', 04:24:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:23 INFO - Downloading/unpacking jsonschema==2.5.1 04:24:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:24:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:24:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:24:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:24:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:28 INFO - Downloading functools32-3.2.3-2.tar.gz 04:24:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:24:28 INFO - warning: no files found matching '*.txt' 04:24:28 INFO - no previously-included directories found matching 'build' 04:24:28 INFO - no previously-included directories found matching 'dist' 04:24:28 INFO - no previously-included directories found matching '.git*' 04:24:28 INFO - Installing collected packages: jsonschema, functools32 04:24:28 INFO - Running setup.py install for functools32 04:24:29 INFO - warning: no files found matching '*.txt' 04:24:29 INFO - no previously-included directories found matching 'build' 04:24:29 INFO - no previously-included directories found matching 'dist' 04:24:29 INFO - no previously-included directories found matching '.git*' 04:24:29 INFO - Successfully installed jsonschema functools32 04:24:29 INFO - Cleaning up... 04:24:29 INFO - Return code: 0 04:24:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:29 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 0x10b036b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1bbca5c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae14d30>, '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 0x10ad3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae09be8>, '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.KnjH3M5z9q/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.7bzrk0XQ7d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:24:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:24:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnjH3M5z9q/Render', 04:24:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:29 INFO - 'HOME': '/Users/cltbld', 04:24:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:29 INFO - 'LOGNAME': 'cltbld', 04:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:29 INFO - 'MOZ_NO_REMOTE': '1', 04:24:29 INFO - 'NO_EM_RESTART': '1', 04:24:29 INFO - 'PAGER': '/bin/cat', 04:24:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:29 INFO - 'PWD': '/builds/slave/test', 04:24:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:29 INFO - 'SHELL': '/bin/bash', 04:24:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bzrk0XQ7d/Listeners', 04:24:29 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 04:24:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:29 INFO - 'USER': 'cltbld', 04:24:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:29 INFO - 'XPC_FLAGS': '0x0', 04:24:29 INFO - 'XPC_SERVICE_NAME': '0', 04:24:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:24:29 INFO - Cleaning up... 04:24:29 INFO - Return code: 0 04:24:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:29 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 0x10b036b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1bbca5c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae14d30>, '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 0x10ad3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae09be8>, '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.KnjH3M5z9q/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.7bzrk0XQ7d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:24:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:24:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnjH3M5z9q/Render', 04:24:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:29 INFO - 'HOME': '/Users/cltbld', 04:24:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:29 INFO - 'LOGNAME': 'cltbld', 04:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:29 INFO - 'MOZ_NO_REMOTE': '1', 04:24:29 INFO - 'NO_EM_RESTART': '1', 04:24:29 INFO - 'PAGER': '/bin/cat', 04:24:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:29 INFO - 'PWD': '/builds/slave/test', 04:24:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:29 INFO - 'SHELL': '/bin/bash', 04:24:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bzrk0XQ7d/Listeners', 04:24:29 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 04:24:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:29 INFO - 'USER': 'cltbld', 04:24:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:29 INFO - 'XPC_FLAGS': '0x0', 04:24:29 INFO - 'XPC_SERVICE_NAME': '0', 04:24:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:29 INFO - Downloading/unpacking blobuploader==1.2.4 04:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:24:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:32 INFO - Downloading blobuploader-1.2.4.tar.gz 04:24:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:24:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:24:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:24:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:24:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:24:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:24:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:33 INFO - Downloading docopt-0.6.1.tar.gz 04:24:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:24:33 INFO - Installing collected packages: blobuploader, requests, docopt 04:24:33 INFO - Running setup.py install for blobuploader 04:24:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:24:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:24:33 INFO - Running setup.py install for requests 04:24:34 INFO - Running setup.py install for docopt 04:24:34 INFO - Successfully installed blobuploader requests docopt 04:24:34 INFO - Cleaning up... 04:24:34 INFO - Return code: 0 04:24:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:34 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 0x10b036b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1bbca5c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae14d30>, '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 0x10ad3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae09be8>, '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.KnjH3M5z9q/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.7bzrk0XQ7d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:24:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:24:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:24:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnjH3M5z9q/Render', 04:24:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:34 INFO - 'HOME': '/Users/cltbld', 04:24:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:34 INFO - 'LOGNAME': 'cltbld', 04:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:34 INFO - 'MOZ_NO_REMOTE': '1', 04:24:34 INFO - 'NO_EM_RESTART': '1', 04:24:34 INFO - 'PAGER': '/bin/cat', 04:24:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:34 INFO - 'PWD': '/builds/slave/test', 04:24:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:34 INFO - 'SHELL': '/bin/bash', 04:24:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bzrk0XQ7d/Listeners', 04:24:34 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 04:24:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:34 INFO - 'USER': 'cltbld', 04:24:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:34 INFO - 'XPC_FLAGS': '0x0', 04:24:34 INFO - 'XPC_SERVICE_NAME': '0', 04:24:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:24:35 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-Wkv1EK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:24:35 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-DLpfEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:24:35 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-TRAE93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:24:35 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-JcIWhg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:24:35 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-MVr3JV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:24:35 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-UGFEgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:24:35 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-RAnhN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:24:35 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-hrh_wV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:24:35 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-aK0H4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:24:35 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-BXNQ3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:24:36 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-d6kno7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:24:36 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-fqoSNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:24:36 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-8DkNsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:24:36 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-vaCSvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:24:36 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-tgkhHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:24:36 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-tS270a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:24:36 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-kowsK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:24:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:24:36 INFO - Running setup.py install for manifestparser 04:24:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:24:36 INFO - Running setup.py install for mozcrash 04:24:37 INFO - Running setup.py install for mozdebug 04:24:37 INFO - Running setup.py install for mozdevice 04:24:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:24:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:24:37 INFO - Running setup.py install for mozfile 04:24:37 INFO - Running setup.py install for mozhttpd 04:24:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:24:37 INFO - Running setup.py install for mozinfo 04:24:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:24:37 INFO - Running setup.py install for mozInstall 04:24:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:24:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:24:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:24:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:24:37 INFO - Running setup.py install for mozleak 04:24:38 INFO - Running setup.py install for mozlog 04:24:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:24:38 INFO - Running setup.py install for moznetwork 04:24:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:24:38 INFO - Running setup.py install for mozprocess 04:24:38 INFO - Running setup.py install for mozprofile 04:24:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:24:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:24:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:24:38 INFO - Running setup.py install for mozrunner 04:24:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:24:38 INFO - Running setup.py install for mozscreenshot 04:24:39 INFO - Running setup.py install for moztest 04:24:39 INFO - Running setup.py install for mozversion 04:24:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:24:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:24:39 INFO - Cleaning up... 04:24:39 INFO - Return code: 0 04:24:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:39 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 0x10b036b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1bbca5c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae14d30>, '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 0x10ad3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae09be8>, '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.KnjH3M5z9q/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.7bzrk0XQ7d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:24:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:24:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:24:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnjH3M5z9q/Render', 04:24:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:39 INFO - 'HOME': '/Users/cltbld', 04:24:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:39 INFO - 'LOGNAME': 'cltbld', 04:24:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:39 INFO - 'MOZ_NO_REMOTE': '1', 04:24:39 INFO - 'NO_EM_RESTART': '1', 04:24:39 INFO - 'PAGER': '/bin/cat', 04:24:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:39 INFO - 'PWD': '/builds/slave/test', 04:24:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:39 INFO - 'SHELL': '/bin/bash', 04:24:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bzrk0XQ7d/Listeners', 04:24:39 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 04:24:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:39 INFO - 'USER': 'cltbld', 04:24:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:39 INFO - 'XPC_FLAGS': '0x0', 04:24:39 INFO - 'XPC_SERVICE_NAME': '0', 04:24:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:24:39 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-CIpRAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:24:39 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-w1nPHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:24:39 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-vIhrnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:24:39 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-h36Ttz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:24:40 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)) 04:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:24:40 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-6Sqr4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:24:40 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-14lb1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:24:40 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-VEfYl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:24:40 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-9SkbBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:24:40 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-iN0wnw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:24:40 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-Idgzbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:24:40 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-b7PQFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:24:40 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-jvVsGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:24:40 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-cypKPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:24:41 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-WywYcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:24:41 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-C4sYFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:24:41 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-hYrtsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:24:41 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-G_KoHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:24:41 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)) 04:24:41 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)) 04:24:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:24:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:24:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:24:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:44 INFO - Downloading blessings-1.6.tar.gz 04:24:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:24:44 INFO - Installing collected packages: blessings 04:24:44 INFO - Running setup.py install for blessings 04:24:44 INFO - Successfully installed blessings 04:24:44 INFO - Cleaning up... 04:24:44 INFO - Return code: 0 04:24:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:24:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:24:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:24:44 INFO - Reading from file tmpfile_stdout 04:24:44 INFO - Current package versions: 04:24:44 INFO - blessings == 1.6 04:24:44 INFO - blobuploader == 1.2.4 04:24:44 INFO - docopt == 0.6.1 04:24:44 INFO - functools32 == 3.2.3-2 04:24:44 INFO - jsonschema == 2.5.1 04:24:44 INFO - manifestparser == 1.1 04:24:44 INFO - mozInstall == 1.12 04:24:44 INFO - mozcrash == 1.0 04:24:44 INFO - mozdebug == 0.1 04:24:44 INFO - mozdevice == 0.49 04:24:44 INFO - mozfile == 1.2 04:24:44 INFO - mozhttpd == 0.7 04:24:44 INFO - mozinfo == 0.9 04:24:44 INFO - mozleak == 0.1 04:24:44 INFO - mozlog == 3.4 04:24:44 INFO - moznetwork == 0.27 04:24:44 INFO - mozprocess == 0.23 04:24:44 INFO - mozprofile == 0.28 04:24:44 INFO - mozrunner == 6.13 04:24:44 INFO - mozscreenshot == 0.1 04:24:44 INFO - mozsystemmonitor == 0.3 04:24:44 INFO - moztest == 0.8 04:24:44 INFO - mozversion == 1.4 04:24:44 INFO - psutil == 3.1.1 04:24:44 INFO - requests == 1.2.3 04:24:44 INFO - wsgiref == 0.1.2 04:24:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b036b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1bbca5c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae14d30>, '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 0x10ad3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae09be8>, '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.KnjH3M5z9q/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.7bzrk0XQ7d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:24:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:24:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:24:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnjH3M5z9q/Render', 04:24:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:44 INFO - 'HOME': '/Users/cltbld', 04:24:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:44 INFO - 'LOGNAME': 'cltbld', 04:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:44 INFO - 'MOZ_NO_REMOTE': '1', 04:24:44 INFO - 'NO_EM_RESTART': '1', 04:24:44 INFO - 'PAGER': '/bin/cat', 04:24:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:44 INFO - 'PWD': '/builds/slave/test', 04:24:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:44 INFO - 'SHELL': '/bin/bash', 04:24:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bzrk0XQ7d/Listeners', 04:24:44 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 04:24:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:44 INFO - 'USER': 'cltbld', 04:24:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:44 INFO - 'XPC_FLAGS': '0x0', 04:24:44 INFO - 'XPC_SERVICE_NAME': '0', 04:24:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:24:44 INFO - Cleaning up... 04:24:44 INFO - Return code: 0 04:24:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b036b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1bbca5c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae14d30>, '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 0x10ad3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae09be8>, '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.KnjH3M5z9q/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.7bzrk0XQ7d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:24:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:24:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:24:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnjH3M5z9q/Render', 04:24:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:44 INFO - 'HOME': '/Users/cltbld', 04:24:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:44 INFO - 'LOGNAME': 'cltbld', 04:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:44 INFO - 'MOZ_NO_REMOTE': '1', 04:24:44 INFO - 'NO_EM_RESTART': '1', 04:24:44 INFO - 'PAGER': '/bin/cat', 04:24:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:44 INFO - 'PWD': '/builds/slave/test', 04:24:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:44 INFO - 'SHELL': '/bin/bash', 04:24:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bzrk0XQ7d/Listeners', 04:24:44 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 04:24:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:44 INFO - 'USER': 'cltbld', 04:24:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:44 INFO - 'XPC_FLAGS': '0x0', 04:24:44 INFO - 'XPC_SERVICE_NAME': '0', 04:24:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:24:45 INFO - Cleaning up... 04:24:45 INFO - Return code: 0 04:24:45 INFO - Running post-action listener: _resource_record_post_action 04:24:45 INFO - Running post-action listener: _start_resource_monitoring 04:24:45 INFO - Starting resource monitoring. 04:24:45 INFO - [mozharness: 2017-01-01 12:24:45.219212Z] Finished create-virtualenv step (success) 04:24:45 INFO - [mozharness: 2017-01-01 12:24:45.220157Z] Running install step. 04:24:45 INFO - Running pre-action listener: _resource_record_pre_action 04:24:45 INFO - Running main action method: install 04:24:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:24:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:24:45 INFO - Reading from file tmpfile_stdout 04:24:45 INFO - Detecting whether we're running mozinstall >=1.0... 04:24:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:24:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:24:45 INFO - Reading from file tmpfile_stdout 04:24:45 INFO - Output received: 04:24:45 INFO - Usage: mozinstall [options] installer 04:24:45 INFO - Options: 04:24:45 INFO - -h, --help show this help message and exit 04:24:45 INFO - -d DEST, --destination=DEST 04:24:45 INFO - Directory to install application into. [default: 04:24:45 INFO - "/builds/slave/test"] 04:24:45 INFO - --app=APP Application being installed. [default: firefox] 04:24:45 INFO - mkdir: /builds/slave/test/build/application 04:24:45 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'] 04:24:45 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 04:25:07 INFO - Reading from file tmpfile_stdout 04:25:07 INFO - Output received: 04:25:07 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:25:07 INFO - Running post-action listener: _resource_record_post_action 04:25:07 INFO - [mozharness: 2017-01-01 12:25:07.052633Z] Finished install step (success) 04:25:07 INFO - [mozharness: 2017-01-01 12:25:07.052765Z] Running run-tests step. 04:25:07 INFO - Running pre-action listener: _resource_record_pre_action 04:25:07 INFO - Running main action method: run_tests 04:25:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:25:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:25:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:25:07 INFO - 2017-01-01 04:25:07.066 screenresolution[1709:8339] starting screenresolution argv=screenresolution get 04:25:07 INFO - 2017-01-01 04:25:07.069 screenresolution[1709:8339] Display 0: 1600x1200x32@60 04:25:07 INFO - 2017-01-01 04:25:07.075 screenresolution[1710:8341] starting screenresolution argv=screenresolution list 04:25:07 INFO - Available Modes on Display 0 04:25:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:25:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:25:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:25:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:25:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:25:07 INFO - Intel Iris: 04:25:07 INFO - Chipset Model: Intel Iris 04:25:07 INFO - Type: GPU 04:25:07 INFO - Bus: Built-In 04:25:07 INFO - VRAM (Dynamic, Max): 1536 MB 04:25:07 INFO - Vendor: Intel (0x8086) 04:25:07 INFO - Device ID: 0x0a2e 04:25:07 INFO - Revision ID: 0x0009 04:25:07 INFO - Displays: 04:25:07 INFO - Display: 04:25:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:25:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:25:07 INFO - Display Serial Number: 1600x1200 60 04:25:07 INFO - Main Display: Yes 04:25:07 INFO - Mirror: Off 04:25:07 INFO - Online: Yes 04:25:07 INFO - Rotation: Supported 04:25:07 INFO - Return code: 0 04:25:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:25:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:25:07 INFO - Python 2.7.3 04:25:07 INFO - Return code: 0 04:25:07 INFO - grabbing minidump binary from tooltool 04: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')]} 04:25:07 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 0x10ae14d30>, '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 0x10ad3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae09be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:25:07 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:25:07 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:25:07 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:25:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:25:07 INFO - Return code: 0 04:25:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:25:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:25:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:25:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:25:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:25:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:25:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0021 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:25:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnjH3M5z9q/Render', 04:25:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:07 INFO - 'HOME': '/Users/cltbld', 04:25:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:07 INFO - 'LOGNAME': 'cltbld', 04:25:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:25:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:07 INFO - 'MOZ_NO_REMOTE': '1', 04:25:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:25:07 INFO - 'NO_EM_RESTART': '1', 04:25:07 INFO - 'PAGER': '/bin/cat', 04:25:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:07 INFO - 'PWD': '/builds/slave/test', 04:25:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:25:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:07 INFO - 'SHELL': '/bin/bash', 04:25:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bzrk0XQ7d/Listeners', 04:25:07 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 04:25:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:07 INFO - 'USER': 'cltbld', 04:25:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:07 INFO - 'XPC_FLAGS': '0x0', 04:25:07 INFO - 'XPC_SERVICE_NAME': '0', 04:25:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:25:07 INFO - mozversion application_buildid: 20170101030204 04:25:07 INFO - mozversion application_changeset: cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 04:25:07 INFO - mozversion application_display_name: Nightly 04:25:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:25:07 INFO - mozversion application_name: Firefox 04:25:07 INFO - mozversion application_remotingname: firefox 04:25:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:25:07 INFO - mozversion application_vendor: Mozilla 04:25:07 INFO - mozversion application_version: 53.0a1 04:25:07 INFO - mozversion platform_buildid: 20170101030204 04:25:07 INFO - mozversion platform_changeset: cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 04:25:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:25:07 INFO - mozversion platform_version: 53.0a1 04:25:07 INFO - using testdate: 1483273507 04:25:07 INFO - actual date: 1483273507 04:25:07 INFO - starting webserver on 'localhost:49244' 04:25:07 INFO - SUITE-START | Running 2 tests 04:25:07 INFO - TEST-START | dromaeo_css 04:25:07 INFO - operating with platform_type : mac_ 04:25:07 INFO - Initialising browser for dromaeo_css test... 04:25:07 INFO - TEST-INFO | started process 1720 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmpbOCUHa/profile http://localhost:49244/getInfo.html) 04:25:08 INFO - PROCESS | 1720 | 1483273508315 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 04:25:10 INFO - PROCESS | 1720 | __metrics Screen width/height:1600/1200 04:25:10 INFO - PROCESS | 1720 | colorDepth:24 04:25:10 INFO - PROCESS | 1720 | Browser inner width/height: 1024/689 04:25:10 INFO - PROCESS | 1720 | __metrics 04:25:10 INFO - PROCESS | 1720 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:25:10 INFO - PROCESS | 1720 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:25:11 INFO - TEST-INFO | 1720: exit 0 04:25:11 INFO - Browser initialized. 04:25:11 INFO - Running cycle 1/1 for dromaeo_css test... 04:25:11 INFO - TEST-INFO | started process 1724 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmpbOCUHa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:25:23 INFO - PROCESS | 1724 | RSS: Main: 225931264 04:25:23 INFO - PROCESS | 1724 | 04:26:14 INFO - PROCESS | 1724 | 1483273574171 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:28:27 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 04:28:29 INFO - PROCESS | 1724 | RSS: Main: 442933248 04:28:29 INFO - PROCESS | 1724 | 04:30:11 INFO - PROCESS | 1724 | ************************* 04:30:11 INFO - PROCESS | 1724 | A coding exception was thrown and uncaught in a Task. 04:30:11 INFO - PROCESS | 1724 | 04:30:11 INFO - PROCESS | 1724 | Full message: ReferenceError: fetch is not defined 04:30:11 INFO - PROCESS | 1724 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:30:11 INFO - PROCESS | 1724 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:30:11 INFO - PROCESS | 1724 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:30:11 INFO - PROCESS | 1724 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:30:11 INFO - PROCESS | 1724 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:30:11 INFO - PROCESS | 1724 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:30:11 INFO - PROCESS | 1724 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:30:11 INFO - PROCESS | 1724 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:30:11 INFO - PROCESS | 1724 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:30:11 INFO - PROCESS | 1724 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:30:11 INFO - PROCESS | 1724 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:30:11 INFO - PROCESS | 1724 | 04:30:11 INFO - PROCESS | 1724 | ************************* 04:31:32 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 04:31:33 INFO - PROCESS | 1724 | RSS: Main: 365715456 04:31:33 INFO - PROCESS | 1724 | 04:34:35 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 04:34:36 INFO - PROCESS | 1724 | RSS: Main: 361746432 04:34:36 INFO - PROCESS | 1724 | 04:37:38 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 04:37:39 INFO - PROCESS | 1724 | RSS: Main: 340402176 04:37:39 INFO - PROCESS | 1724 | 04:40:42 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 04:40:43 INFO - PROCESS | 1724 | RSS: Main: 325144576 04:40:43 INFO - PROCESS | 1724 | 04:43:46 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 04:43:46 INFO - PROCESS | 1724 | RSS: Main: 378236928 04:43:46 INFO - PROCESS | 1724 | 04:43:46 INFO - PROCESS | 1724 | __start_tp_report 04:43:46 INFO - PROCESS | 1724 | _x_x_mozilla_page_load 04:43:46 INFO - PROCESS | 1724 | _x_x_mozilla_page_load_details 04:43:46 INFO - PROCESS | 1724 | |i|pagename|runs| 04:43:46 INFO - PROCESS | 1724 | |0;dojo.html;3351.8659253629858;3363.915902102449;3383.289509203067;3399.0142858571016;3429.108431807729;5514.889702205954;5552.611317207797;5762.078067509199;5775.566832487564;5804.332501762298;5601.579881508883;5734.225879506265;5826.796062137826;5829.679367634779;5843.2695913010875;4777.450593181794;4961.900761984776;5163.741812909339;5221.973890130544;5229.111051121318;4598.793884919718;5319.148936170213;5333.519983201511;5351.277577527042;5405.891882162355;5140.3831540215415;5279.41926388097;5309.230161626581;5316.9202461963105;5342.118550439192;5163.225516172567;5176.378834539879;5240.502152295564;5257.237700533401;5273.499017593323;5210.843674689728;5234.581233501311;5274.05067087924;5283.260343551906;5293.285406470116;4774;5746.138079288098;5746.712664366765;5749.568782341299;5763.250777398953;6132.938670613281;6149.477294429978;6252.906206406908;6275.937240627581;6285.214348206474;6154.661493617849;6200.81397558074;6237.469815065725;6240.157578726859;6280.403361680633;5848.976429124886;5849.6197747146725;5852.268466441695;5857.882842343214;5894.675792831393;6152.3540028297275;6297.590656607306;6302.684865756693;6304.936950630435;6312.715927783261;6033.78881739137;6100.145979562864;6113.755449782047;6116.510679145657;6176.505879529627;6130.122981477994;6204;6238.1890354254565;6257.2491301044165;6261.96869015652;6120.479759220516;6120.660465672121;6157.0456579230295;6157.476614487729;6219.098230756515;6266.592671476339;6266.62400255977;6275.717592708339;6292.528060395489;6313.116163736989;4887.486813884562;4939.234418665113;5017.799288028511;5019.222020586815;5038.546530812245;4441.533638967927;4488.730676159441;4508.45898492183;4509.661775366861;4511.842085526991;5786.623869448473;5914.497267732291;5915.704214789243;5952.285725712855;5979.222701048923;6221.3156552779155;6238.469730072995;6244.313125556185;6259.311475737665;6263.592866463665;5904.439078287556;5906.822795316061;5931.258592675916;5931.673757943268;5969.403059693996;5327.121025030827;5357.223202635596;5370.221317908881;5404.621676482609;5418.945810541923;6212.502999760009;6214.223222097147;6267.310595834363;6343.809685709436;6426.582272152295;6155.076738489262;6208.192934918432;6217.067439884054;6217.4715149212825;6241.469475094668;6411.230652321751;6421.486281097407;6424.068510066108;6424.839379015562;6427.646479443675;6079.604825686316;6106.915739268539;6120.386183820725;6137.867325793431;6142.775043102407;4780.784864681028;6273.905891411541;6276.027215781562;6283.088952101828;6285.59143655661;6090.878182436415;6136.20229370179;6175.505959523138;6180.598261113013;6184.628922264678;5998.280206375263;6056.091586262096;6083.7870674526175;6095.847603809941;6119.357467465852;5795.24661793981;6031.577789554689;6038.305594856648;6038.969805150946;6101.542384321282;5323.840284791462;5881.852953676193;6046.1535385045245;6121.051237058263;6141.692915354304;5869.765209391577;5934.139549765174;5952.464278214981;6016.187814645142;6115.663638499926 04:43:46 INFO - PROCESS | 1724 | |1;ext.html;463.92138278502483;473.85310553728334;527.3618921105463;536.6163193316776;613.2793967088672;37293.81353093234;39718.602813971855;39839.800800996025;41751;41843.372349414705;40916.181676366454;41325.38011929816;41352.37971430431;41357;41366.58633413665;8431;8664.480131192116;9231.123043310892;9452.542338991843;9453.291003174754;10964.616238431656;11278.041366483858;11291.717707057349;11404.486798094105;11455.828162577567;11171.66485005451;11240.381779002193;11242.269252498601;11254.043406310433;11264.662060138167;11290.887091129107;11360.431978401048;11363.090952723762;11367.658970230907;11385.54457821686;9527.761805954837;11155.219134660578;11235.21353505255;11244.662660120128;11246;38007.42988855169;38203.617963820565;38215.426768598496;38306.23387532234;38338.04154896122;3923.156521347924;3925.8625948091967;4024.9798751006056;4028.395740638898;4112.835486580533;28948.128667204764;29744.55383169254;29811.850940745375;30116.24709382261;30140.24649383761;3133.3263348380206;3263.1352691536763;3273.2798784267425;3335.6497567755277;3338.43246648068;456815;457050;457680;457833;459083;54086;54359;54379;54422.18366724502;54574.727126364116;57423.42576574264;57510.42489575134;57788.71105644445;58040.356771459024;58129.4187058124;15078.944473886722;15294.694106117815;15311.617209569627;15329.616759580877;15335.539933802003;10693.518791654395;10976.780464390667;11007.339559626287;11623.418829058513;11912.642620721392;14711.337989790485;14920.850791492163;14940.879434042274;14953.177575233467;15046.097234165985;5957.553183511254;6942.791716248521;6943.79168624942;6994.230634630187;7044.894326585106;736.6905899522213;865.9393842431095;890.4167770110588;909.4588719711761;921.801812610292;27765.583516247272;29082.41835163327;29328.413431731246;29582;29621.259468513028;1038.3458421194598;1050.1598721023151;1083.8916108389099;1089.5641743302742;1198.4007996002;12006.939965300118;13772.48502664726;14632.634184145269;15059.774103388458;15423.691526169414;488421;580290;581018;581558;584032;55833.441665582824;55950.160747588816;55991.72004139953;56111;56121.71939140278;1370.294298436306;1402.5161319344809;1407.9014468987073;1415.4974983880575;1425.836028856674;27502;28288.434231314848;28514;28607.570886437137;29125.417491649623;46506.767466162455;46976;47107.057858841945;47121.057578848915;47128.29307560458;486743;487966.8838083524;489920;491631;492920;32173.517397239528;33196.67213311537;36848;36910.8154459226;38208.617913820504;43937.78031109824;44020.55979440165;44387.778061109486;44498.3325250128;45245;36582.26835463329;37056.55321361454;37124.25751485009;37510.43734344042;37939.05152371102;36481.452778207815;36662;37491.6250837499;37660.62339376681;37806.24387512289;28424.28939276452;35284.647153529164;35882;36604.81697591495;36714;30867.38265234638;30873;31997.680023200403;32009.199770005936;33559.66440335663;22844.31467055991;23651;23957;24457;24659.78061974431 04:43:46 INFO - PROCESS | 1724 | |2;jquery.html;340.9641987591301;371.58753783300534;373.74398537002156;382.11730901617256;399.7641391578968;14754.188519631429;14973.176475293854;15565.999999999985;19775.208991640317;20330.39008829734;19094.522636934085;19563.608727825434;20075.899620501914;20365.083571239295;20378.796212037876;5600.747966341524;5754.65472071675;5794.188813566104;5797.043487824525;5797.36229014808;4931.852044438672;6315.368463153671;6404.071409645599;6455.548111632188;6455.741770329181;6131.3562075982045;6271.560990730651;6377.617342959414;6402.93597064028;6425.421712045938;6325.778597749079;6367.6497792621385;6386.42522173002;6388.8083357499345;6391.073294372313;5256.8685782855355;5322.547583455411;5554;5567.66594004361;5574.275344205227;25175.118870839615;25221.495570088497;25247;25359.746402536108;25530.617040744404;2574.4464940037983;2654.5487267164626;2675.2241849863517;2718.184544636621;2752.683441404244;14169.716605667933;14848.406063757437;15091.320890559842;15402.922985385114;15502.9224853875;1822.8906265624105;1830.2313028528051;1852.740616313717;1874.259667431357;1899.705545640428;114804.42597787041;119333;119340;119881;121390;10728.141748660088;10828.945855270673;10838.40388778609;11368.488418021208;11406.42967851604;11061.612843550438;14280.85719142816;14451;14597.562073137822;14601.904857135758;6831.487638427137;8150.225728555778;8231.711890083818;8271.54506502136;8291.543965081857;7703.189750408649;7781.649825757854;8057.153998830156;8172.223638754309;8175.305099066527;4375;4428.158649856517;4443.755593442392;4590.816367345269;4591.908161836812;1884.2839720905968;1930.5752734398409;1935.5451468904796;1936.3803582853352;1940.4469726127986;837.9483747896351;852.7271273192518;934.2619330728784;980.7940332530248;986.7829077602916;19232.519187020436;20699;20759.68860467094;20814;20852.895735521226;836.5566249887569;935.1490143969091;954.2938225712923;978.9021097890154;1032.3289861590026;8733;10873.184511161537;10906.836397454044;10935.343879367263;10971.780564388828;320545;321133;387771.4674239109;564977;569883;121955;122315;122596.38701806434;124675;125285;925.3383830561181;978.4862946952907;990.1138481059555;990.5393991793851;994.6916455898549;18027.819721802614;18207;18423.355182568546;18533.629327413644;18618.534536636693;52887;52914.47085529096;53534.70212117128;54041;54281.45718542922;76463;77229.91080356723;77976;78239.21760782319;79023;7889.723859664884;8077.7576672699915;8196.877046844422;8201.30288925448;8285.461445006054;11491.885081149416;12266.693332666755;12692.85764281219;12737.55418560346;12756.87243127594;9046.502442365732;10927.781444371227;11203.031196732709;11212.15908806859;11243.212975091665;10056.748581285234;10152.137068348977;10375.844362334721;10926.524919135814;10966.287191332538;11250.549978000789;11382;11463.828042579535;11506.539738410369;11538.807626550928;7837.451378403685;7901.130875603794;7983.920160798318;8050.758477245692;8151.150862026045;8113.269805717514;9413;9893.802123957625;10665.9466702666;11131.332120072822 04:43:46 INFO - PROCESS | 1724 | |3;mootools.html;1307.921524708518;1403.670137517683;1405.409727914276;1424.1597457500077;1426.9928650356746;7323.853522929545;7491.66287517061;7723.227677232274;8244;8291.751247462586;8093.838123237531;8195.631196596152;8201.179882011797;8203.466774659642;8205.58972051398;2046.9385918422472;2274.1358283852114;2315.9925432436858;2317.8377513574055;2321.106374045996;2132.893355332235;2255.5939930812447;2331.9650205246935;2333.8132949364012;2347.7182738071456;2252.335561009506;2272.7613600571867;2281.0191617604423;2281.817454603636;2282.6005449046434;2283.862968221904;2298.310506847956;2303.1823702585507;2303.527776805751;2307.561563302984;2281.7946384825277;2311.5492478966607;2315.22439982606;2315.490592069742;2325.430269583946;8207.302379297767;8216.712415065504;8262.008558972962;8342.541160236246;8344.958275208646;4973.825916092919;5121.231815227746;5144.794208231666;5146.536811686966;5159.613029022838;2963.733264006228;3287.7862938909134;3443.724502039831;3482.3905816482015;3516.1736991806906;1151.0733859243305;1207.2153100484668;1208;1242.273270136971;1262.1291308996763;10478.476076196159;10601.41692206936;10602;10609.045185933302;10637;4325.264705000158;4433.467983841895;4497.190505708938;4508.368828363966;4518.9548104518535;8546.31629469641;9233.769155771159;9248.630054797868;9254.491002994766;9299.581518831668;5097.898042039138;5120.18077107661;5121.078205922895;5124;5138.100832354323;4441.422615060022;4623.884402889955;4657.510961349009;4677.579017888406;4694.624430045588;3687.5574931007914;3715.0898029982827;3747;3784.791836449022;3793.3740932745786;1238.8265642810013;1248.1200753468963;1249.2504497301547;1252.2611659121162;1265.5886836778084;843.1694780641063;868.5830801215452;875.7285241575005;908.6819613135482;923.7599418668344;5211.140161873249;5319.893602127936;5469.206964990134;5542.057850165482;5611.298587676541;519.7037688517509;537.5645726961174;538.2921458282347;543.9129739241781;545.3946119807011;3459.6540345965204;3494.545709057857;3519.419295816213;3529.5411596492804;3593.1735700788963;9502.47736374506;9781.217502599775;9889.752756181153;9921.950390248003;9924.603015879284;9436.811263774824;9872.1608663263;9872.901270987342;9888.9616590257;9891.653792117242;5815.592908496449;7623.237676232332;7664.846703066019;7672.0409948756405;7692.46152769312;4337.956620433755;4803.471618122074;5235.319408476874;5355.652717051843;5416.485433883775;6862.936244882051;8517.95741021291;8917.28661707075;8954.865677014719;8980.416272942499;9076.636934522545;9162.26701863836;9272.119148680864;9294.814103717752;9297.07029297065;6491.28595854465;6668.466522678271;6697.129373181455;6802.251752307143;6980.720771169096;7802;7823.022122234721;7896.7236146734585;7901.972743543302;7902.091259505;5996.850078748066;6968.059311992816;6998.6500674967065;7072.893906591508;7139.178994415709;6724.731010759515;6825.283345248876;6834.45324374039;7003.824904377431;7006.439484841098;5617.662940223599;6051.789642071515;6147.44672979434;6295.874082518416;6324.30432652417;4714.834980775793;4715.952840471551;4717.127331443694;4742.976285118552;4861.124997500484;4643.210654188729;4644.094401591792;4720.079584481131;4801.5438533339275;4924.064427758714 04:43:46 INFO - PROCESS | 1724 | |4;prototype.html;348.64089987313065;351.5640605648994;357.4548813060083;368.68477451778745;368.71608861176895;5121.206213036985;5278.498542638453;5329.561018525001;5343.465653434655;5374.679067722956;4887.780049897754;4977.377827771528;4983.626228032903;4990.1556113015595;5138.972205558894;4175.770332631704;4208.600182982619;4215.85244516442;4231.788410579458;4240.151969606076;3808.238352329531;3836.5204349456317;4201.852935147271;4224.943764058985;4240.194363071022;3902.540670070102;4062.7359221650645;4148.979255103735;4153.418521407006;4179.498460184782;3284.458064419368;3526.806025668587;3997.5003124609425;4107.54816970133;4120.814563344657;3161.7944833585743;3239.3521295740948;3510.3681337359267;3638.362736768465;3648.5372373647683;4401.713888597263;4883.145449546315;4886.169351210267;4970.7324632218915;5175.870603234912;3555.7732582259077;3742.4760533525327;3871.593482684306;3898.577019387931;4562.745245057603;5129.7435128243815;5165.535101840815;5174;5435.918461223085;5528.723563821793;3266.591676040495;3779.6787273081823;3793.886183414502;3797.373433383516;3807.1243613968663;5067.961067981092;5567.49892509672;5756.136579513065;5771.538276937835;5811.564132690065;3832;3847.5575308839843;3849.095462566295;3859.9035024124623;4030.7581545107387;3700.389435743126;3908.863189788344;3916.2754890345377;3925.5878132795647;3930.371140617545;3958.91129939269;4269.3595960605535;4448.35498852671;4530.048689775184;4564;4767.831881189061;5535.778568857281;5551.944480555224;5573.191887176417;5580.791045149055;1109.0074383426788;1222.9877701222874;1253.6552448076852;1253.72746662138;1256.604169686564;775.9718373155479;794.8950958168151;818.7461886815106;822.7284995951321;841.4068082002174;4551.520755754393;4960.454350021495;5006.723285562159;5015.623828212899;5058.519440653132;4511.714161464032;4613.700109492872;4939.851804445872;5239;5347.893042139135;4410.845620403271;4710.86727326331;4733.5503127202865;4945.799119391836;5014.89970200594;2619.2404202781363;2691.757741803247;2703.7431444012786;2718.3340081680117;2828.5050116229577;4722.70125715431;5153.922691159635;5505.476979686923;5552.639078459887;5576.219329293902;4872.439669437989;5151.922721159186;5181.3782346118705;5395.81114660985;5510.614257001971;5311.548518375981;5368.295875845654;5499.058003967858;5509.834704958857;5562.666240025612;4630.351750754964;4808.0383923214795;5013.197888337849;5355.920345801102;5363.731813409236;4430;5202.271681964604;5259.921101183409;5584.235803277404;5632.352279487911;5959.106134079922;5968.552358573038;6016.548758843017;6020.54845886569;6043.3352331244405;3549.18368775178;3811.58072612018;3845.4808600839647;3849.210911763138;3869.342211824053;5624.74688639004;5657.575681823797;5715.39988301239;5725.621883088831;5739.938111449395;5512.614117011771;5517.944820551743;5556.666600004012;5570.24801651788;5575.661841158174;4379.737215767064;5464;5518.53092487127;5520.447955204448;5584.301962254718;4380.934285985649;4529.818807247805;5281.762320695655;5409.432009639088;5452.563794896478;5146.871328216825;5310.415854256106;5449.100898351728;5481.479259470344;5493.423190564933;4605;4874.4150701916;4937.77779999908;5396.487333703292;5556.861078473071 04:43:46 INFO - PROCESS | 1724 | |5;yui.html;702.5328156775745;716.1442076718324;761.1361105145661;796.2323641515834;796.5643831821014;14644.926775366122;15174.393024279016;15562.988405753633;15568.37726490939;15589.376424943017;14077.114191590725;14206.36071376788;14236.928815355936;14262.50081247154;14274.286285685726;675.8884784010633;700.1807884774826;701.2251462134344;828.6478246745139;846.4075147396816;712.4087007783553;854.7692123126752;861.6553378648528;865.2991077227434;867.1935100356666;822.4201937633953;909.917198533746;920.5627327019679;921.9815603687922;925.6852670092168;916.4730280088942;919.3197034194715;919.8804155459815;921.1019256225227;922.3451349541833;524.2581746828233;541.1612001397857;649.0426620734427;672.146374104889;692.2143367278126;14684.926575367163;14728.705425891529;14751.041182323115;14760.852391476055;14771.965962382637;11192.608258710907;13341.332933353391;13388;13512.391942362618;13559.864401355959;10674.306170098918;10976;11266.155038372155;11266.66200014001;12301.507939682491;4355.629771469429;4391.780410979438;4431.689781715281;4471.8658440246845;4571.422859113601;94922;103471.48264258706;104306.95693043125;105641;107829.92170078277;10547.789044219226;10580.57677692899;10600.946995264974;10753.408562528979;10787.56849726018;18785.624287514172;19446.222151113798;20615.072321745556;20684.276050338165;20690.17239310411;7213;7306.634668266565;7376.299251571092;7457.627118644046;7482.401407887465;4136.860642118771;4172.687048471377;4343.961892968776;4352.273691249025;4389.531127302372;3483.1814523586936;3565.6612621800887;3582.355176068281;3644.1436262478296;3644.927101458009;608.9330173680892;645.8332295149705;648.6410969019896;657.907892894995;673.8079647300495;467.7918326344744;654.3379815269258;665.311402698205;666.3170250493187;667.1993607670814;8804.955975220211;9165.31260155491;9317.068293170629;9393.5303234838;9522.571484283224;638.8498702804837;651.7653644687908;674.7197736423931;676.4106722572726;682.2120450879243;4355.891102722458;4448.5551444855255;4507.3689683444345;4541.500434952152;4584.724916505021;69635;70545;86989;90370;92238.07761922429;85528.57235713906;86676;87379;88022.23955520854;88995.22009559773;76578;77659;79685.60157199293;79827.4034519318;80719.59640201762;8395.95802020986;8692.521911294687;8709.738707838775;8711.21599056088;8957;36577.62245454037;52069;52289;52569.68575785636;53857.46142538524;60023.7995240084;69947.65026174836;70107;70278.29721702718;73046.63476682795;11066.723331916766;11245.606403775828;11339.376334301363;11437.19939604253;11651.88348116508;12470.937645311715;12892.742145156857;13085.803712944504;13277.35946775018;13316.40076196554;11843;11867.287962722265;12175.048076444207;12380.690482738004;12406.007519398247;11789.764204715686;11835.763284734085;11955;11959.641210763692;12311.630651080482;11630;12053.096017798876;12155.209911355734;12289.385530723606;12330.568430104904;9816.950915245377;9868.950655246965;9869.802603947737;10332.690019299433;10355.223358248011;9486.620535178516;9527.71130085499;9604.587384692097;9616.663416780659;9714.077162669535 04:43:46 INFO - PROCESS | 1724 | __end_tp_report 04:43:46 INFO - PROCESS | 1724 | __start_cc_report 04:43:46 INFO - PROCESS | 1724 | _x_x_mozilla_cycle_collect,2480 04:43:46 INFO - PROCESS | 1724 | __end_cc_report 04:43:46 INFO - PROCESS | 1724 | __startTimestamp1483274626961__endTimestamp 04:43:46 INFO - PROCESS | 1724 | 04:43:46 INFO - PROCESS | 1724 | ------- Summary: start ------- 04:43:46 INFO - PROCESS | 1724 | Number of tests: 6 04:43:46 INFO - PROCESS | 1724 | 04:43:46 INFO - PROCESS | 1724 | [#0] dojo.html Cycles:165 Average:5752.15 Median:5998.28 stddev:644.97 (10.8%) stddev-sans-first:618.85 04:43:46 INFO - PROCESS | 1724 | Values: 3351.9 3363.9 3383.3 3399.0 3429.1 5514.9 5552.6 5762.1 5775.6 5804.3 5601.6 5734.2 5826.8 5829.7 5843.3 4777.5 4961.9 5163.7 5222.0 5229.1 4598.8 5319.1 5333.5 5351.3 5405.9 5140.4 5279.4 5309.2 5316.9 5342.1 5163.2 5176.4 5240.5 5257.2 5273.5 5210.8 5234.6 5274.1 5283.3 5293.3 4774.0 5746.1 5746.7 5749.6 5763.3 6132.9 6149.5 6252.9 6275.9 6285.2 6154.7 6200.8 6237.5 6240.2 6280.4 5849.0 5849.6 5852.3 5857.9 5894.7 6152.4 6297.6 6302.7 6304.9 6312.7 6033.8 6100.1 6113.8 6116.5 6176.5 6130.1 6204.0 6238.2 6257.2 6262.0 6120.5 6120.7 6157.0 6157.5 6219.1 6266.6 6266.6 6275.7 6292.5 6313.1 4887.5 4939.2 5017.8 5019.2 5038.5 4441.5 4488.7 4508.5 4509.7 4511.8 5786.6 5914.5 5915.7 5952.3 5979.2 6221.3 6238.5 6244.3 6259.3 6263.6 5904.4 5906.8 5931.3 5931.7 5969.4 5327.1 5357.2 5370.2 5404.6 5418.9 6212.5 6214.2 6267.3 6343.8 6426.6 6155.1 6208.2 6217.1 6217.5 6241.5 6411.2 6421.5 6424.1 6424.8 6427.6 6079.6 6106.9 6120.4 6137.9 6142.8 4780.8 6273.9 6276.0 6283.1 6285.6 6090.9 6136.2 6175.5 6180.6 6184.6 5998.3 6056.1 6083.8 6095.8 6119.4 5795.2 6031.6 6038.3 6039.0 6101.5 5323.8 5881.9 6046.2 6121.1 6141.7 5869.8 5934.1 5952.5 6016.2 6115.7 04:43:46 INFO - PROCESS | 1724 | 04:43:46 INFO - PROCESS | 1724 | [#1] ext.html Cycles:180 Average:64015.19 Median:36983.68 stddev:134697.36 (364.2%) stddev-sans-first:134990.71 04:43:46 INFO - PROCESS | 1724 | Values: 463.9 473.9 527.4 536.6 613.3 37293.8 39718.6 39839.8 41751.0 41843.4 40916.2 41325.4 41352.4 41357.0 41366.6 8431.0 8664.5 9231.1 9452.5 9453.3 10964.6 11278.0 11291.7 11404.5 11455.8 11171.7 11240.4 11242.3 11254.0 11264.7 11290.9 11360.4 11363.1 11367.7 11385.5 9527.8 11155.2 11235.2 11244.7 11246.0 38007.4 38203.6 38215.4 38306.2 38338.0 3923.2 3925.9 4025.0 4028.4 4112.8 28948.1 29744.6 29811.9 30116.2 30140.2 3133.3 3263.1 3273.3 3335.6 3338.4 456815.0 457050.0 457680.0 457833.0 459083.0 54086.0 54359.0 54379.0 54422.2 54574.7 57423.4 57510.4 57788.7 58040.4 58129.4 15078.9 15294.7 15311.6 15329.6 15335.5 10693.5 10976.8 11007.3 11623.4 11912.6 14711.3 14920.9 14940.9 14953.2 15046.1 5957.6 6942.8 6943.8 6994.2 7044.9 736.7 865.9 890.4 909.5 921.8 27765.6 29082.4 29328.4 29582.0 29621.3 1038.3 1050.2 1083.9 1089.6 1198.4 12006.9 13772.5 14632.6 15059.8 15423.7 488421.0 580290.0 581018.0 581558.0 584032.0 55833.4 55950.2 55991.7 56111.0 56121.7 1370.3 1402.5 1407.9 1415.5 1425.8 27502.0 28288.4 28514.0 28607.6 29125.4 46506.8 46976.0 47107.1 47121.1 47128.3 486743.0 487966.9 489920.0 491631.0 492920.0 32173.5 33196.7 36848.0 36910.8 38208.6 43937.8 44020.6 44387.8 44498.3 45245.0 36582.3 37056.6 37124.3 37510.4 37939.1 36481.5 36662.0 37491.6 37660.6 37806.2 28424.3 35284.6 35882.0 36604.8 36714.0 30867.4 30873.0 31997.7 32009.2 33559.7 22844.3 23651.0 23957.0 24457.0 24659.8 04:43:46 INFO - PROCESS | 1724 | 04:43:46 INFO - PROCESS | 1724 | [#2] jquery.html Cycles:180 Average:30250.82 Median:161648.84 stddev:76445.97 (47.3%) stddev-sans-first:76627.44 04:43:46 INFO - PROCESS | 1724 | Values: 341.0 371.6 373.7 382.1 399.8 14754.2 14973.2 15566.0 19775.2 20330.4 19094.5 19563.6 20075.9 20365.1 20378.8 5600.7 5754.7 5794.2 5797.0 5797.4 4931.9 6315.4 6404.1 6455.5 6455.7 6131.4 6271.6 6377.6 6402.9 6425.4 6325.8 6367.6 6386.4 6388.8 6391.1 5256.9 5322.5 5554.0 5567.7 5574.3 25175.1 25221.5 25247.0 25359.7 25530.6 2574.4 2654.5 2675.2 2718.2 2752.7 14169.7 14848.4 15091.3 15402.9 15502.9 1822.9 1830.2 1852.7 1874.3 1899.7 114804.4 119333.0 119340.0 119881.0 121390.0 10728.1 10828.9 10838.4 11368.5 11406.4 11061.6 14280.9 14451.0 14597.6 14601.9 6831.5 8150.2 8231.7 8271.5 8291.5 7703.2 7781.6 8057.2 8172.2 8175.3 4375.0 4428.2 4443.8 4590.8 4591.9 1884.3 1930.6 1935.5 1936.4 1940.4 837.9 852.7 934.3 980.8 986.8 19232.5 20699.0 20759.7 20814.0 20852.9 836.6 935.1 954.3 978.9 1032.3 8733.0 10873.2 10906.8 10935.3 10971.8 320545.0 321133.0 387771.5 564977.0 569883.0 121955.0 122315.0 122596.4 124675.0 125285.0 925.3 978.5 990.1 990.5 994.7 18027.8 18207.0 18423.4 18533.6 18618.5 52887.0 52914.5 53534.7 54041.0 54281.5 76463.0 77229.9 77976.0 78239.2 79023.0 7889.7 8077.8 8196.9 8201.3 8285.5 11491.9 12266.7 12692.9 12737.6 12756.9 9046.5 10927.8 11203.0 11212.2 11243.2 10056.7 10152.1 10375.8 10926.5 10966.3 11250.5 11382.0 11463.8 11506.5 11538.8 7837.5 7901.1 7983.9 8050.8 8151.2 8113.3 9413.0 9893.8 10665.9 11131.3 04:43:46 INFO - PROCESS | 1724 | 04:43:46 INFO - PROCESS | 1724 | [#3] mootools.html Cycles:180 Average:5263.57 Median:5153.07 stddev:2891.00 (56.1%) stddev-sans-first:2883.83 04:43:46 INFO - PROCESS | 1724 | Values: 1307.9 1403.7 1405.4 1424.2 1427.0 7323.9 7491.7 7723.2 8244.0 8291.8 8093.8 8195.6 8201.2 8203.5 8205.6 2046.9 2274.1 2316.0 2317.8 2321.1 2132.9 2255.6 2332.0 2333.8 2347.7 2252.3 2272.8 2281.0 2281.8 2282.6 2283.9 2298.3 2303.2 2303.5 2307.6 2281.8 2311.5 2315.2 2315.5 2325.4 8207.3 8216.7 8262.0 8342.5 8345.0 4973.8 5121.2 5144.8 5146.5 5159.6 2963.7 3287.8 3443.7 3482.4 3516.2 1151.1 1207.2 1208.0 1242.3 1262.1 10478.5 10601.4 10602.0 10609.0 10637.0 4325.3 4433.5 4497.2 4508.4 4519.0 8546.3 9233.8 9248.6 9254.5 9299.6 5097.9 5120.2 5121.1 5124.0 5138.1 4441.4 4623.9 4657.5 4677.6 4694.6 3687.6 3715.1 3747.0 3784.8 3793.4 1238.8 1248.1 1249.3 1252.3 1265.6 843.2 868.6 875.7 908.7 923.8 5211.1 5319.9 5469.2 5542.1 5611.3 519.7 537.6 538.3 543.9 545.4 3459.7 3494.5 3519.4 3529.5 3593.2 9502.5 9781.2 9889.8 9922.0 9924.6 9436.8 9872.2 9872.9 9889.0 9891.7 5815.6 7623.2 7664.8 7672.0 7692.5 4338.0 4803.5 5235.3 5355.7 5416.5 6862.9 8518.0 8917.3 8954.9 8980.4 9076.6 9162.3 9272.1 9294.8 9297.1 6491.3 6668.5 6697.1 6802.3 6980.7 7802.0 7823.0 7896.7 7902.0 7902.1 5996.9 6968.1 6998.7 7072.9 7139.2 6724.7 6825.3 6834.5 7003.8 7006.4 5617.7 6051.8 6147.4 6295.9 6324.3 4714.8 4716.0 4717.1 4743.0 4861.1 4643.2 4644.1 4720.1 4801.5 4924.1 04:43:46 INFO - PROCESS | 1724 | 04:43:46 INFO - PROCESS | 1724 | [#4] prototype.html Cycles:180 Average:4402.78 Median:4912.78 stddev:1340.53 (27.3%) stddev-sans-first:1309.30 04:43:46 INFO - PROCESS | 1724 | Values: 348.6 351.6 357.5 368.7 368.7 5121.2 5278.5 5329.6 5343.5 5374.7 4887.8 4977.4 4983.6 4990.2 5139.0 4175.8 4208.6 4215.9 4231.8 4240.2 3808.2 3836.5 4201.9 4224.9 4240.2 3902.5 4062.7 4149.0 4153.4 4179.5 3284.5 3526.8 3997.5 4107.5 4120.8 3161.8 3239.4 3510.4 3638.4 3648.5 4401.7 4883.1 4886.2 4970.7 5175.9 3555.8 3742.5 3871.6 3898.6 4562.7 5129.7 5165.5 5174.0 5435.9 5528.7 3266.6 3779.7 3793.9 3797.4 3807.1 5068.0 5567.5 5756.1 5771.5 5811.6 3832.0 3847.6 3849.1 3859.9 4030.8 3700.4 3908.9 3916.3 3925.6 3930.4 3958.9 4269.4 4448.4 4530.0 4564.0 4767.8 5535.8 5551.9 5573.2 5580.8 1109.0 1223.0 1253.7 1253.7 1256.6 776.0 794.9 818.7 822.7 841.4 4551.5 4960.5 5006.7 5015.6 5058.5 4511.7 4613.7 4939.9 5239.0 5347.9 4410.8 4710.9 4733.6 4945.8 5014.9 2619.2 2691.8 2703.7 2718.3 2828.5 4722.7 5153.9 5505.5 5552.6 5576.2 4872.4 5151.9 5181.4 5395.8 5510.6 5311.5 5368.3 5499.1 5509.8 5562.7 4630.4 4808.0 5013.2 5355.9 5363.7 4430.0 5202.3 5259.9 5584.2 5632.4 5959.1 5968.6 6016.5 6020.5 6043.3 3549.2 3811.6 3845.5 3849.2 3869.3 5624.7 5657.6 5715.4 5725.6 5739.9 5512.6 5517.9 5556.7 5570.2 5575.7 4379.7 5464.0 5518.5 5520.4 5584.3 4380.9 4529.8 5281.8 5409.4 5452.6 5146.9 5310.4 5449.1 5481.5 5493.4 4605.0 4874.4 4937.8 5396.5 5556.9 04:43:46 INFO - PROCESS | 1724 | 04:43:46 INFO - PROCESS | 1724 | [#5] yui.html Cycles:180 Average:19383.54 Median:26546.97 stddev:27831.56 (104.8%) stddev-sans-first:27874.28 04:43:46 INFO - PROCESS | 1724 | Values: 702.5 716.1 761.1 796.2 796.6 14644.9 15174.4 15563.0 15568.4 15589.4 14077.1 14206.4 14236.9 14262.5 14274.3 675.9 700.2 701.2 828.6 846.4 712.4 854.8 861.7 865.3 867.2 822.4 909.9 920.6 922.0 925.7 916.5 919.3 919.9 921.1 922.3 524.3 541.2 649.0 672.1 692.2 14684.9 14728.7 14751.0 14760.9 14772.0 11192.6 13341.3 13388.0 13512.4 13559.9 10674.3 10976.0 11266.2 11266.7 12301.5 4355.6 4391.8 4431.7 4471.9 4571.4 94922.0 103471.5 104307.0 105641.0 107829.9 10547.8 10580.6 10600.9 10753.4 10787.6 18785.6 19446.2 20615.1 20684.3 20690.2 7213.0 7306.6 7376.3 7457.6 7482.4 4136.9 4172.7 4344.0 4352.3 4389.5 3483.2 3565.7 3582.4 3644.1 3644.9 608.9 645.8 648.6 657.9 673.8 467.8 654.3 665.3 666.3 667.2 8805.0 9165.3 9317.1 9393.5 9522.6 638.8 651.8 674.7 676.4 682.2 4355.9 4448.6 4507.4 4541.5 4584.7 69635.0 70545.0 86989.0 90370.0 92238.1 85528.6 86676.0 87379.0 88022.2 88995.2 76578.0 77659.0 79685.6 79827.4 80719.6 8396.0 8692.5 8709.7 8711.2 8957.0 36577.6 52069.0 52289.0 52569.7 53857.5 60023.8 69947.7 70107.0 70278.3 73046.6 11066.7 11245.6 11339.4 11437.2 11651.9 12470.9 12892.7 13085.8 13277.4 13316.4 11843.0 11867.3 12175.0 12380.7 12406.0 11789.8 11835.8 11955.0 11959.6 12311.6 11630.0 12053.1 12155.2 12289.4 12330.6 9817.0 9869.0 9869.8 10332.7 10355.2 9486.6 9527.7 9604.6 9616.7 9714.1 04:43:46 INFO - PROCESS | 1724 | -------- Summary: end -------- 04:43:46 INFO - PROCESS | 1724 | 04:43:47 INFO - TEST-INFO | 1724: exit 0 04:43:47 INFO - TEST-OK | dromaeo_css | took 1119923ms 04:43:47 INFO - TEST-START | kraken 04:43:47 INFO - operating with platform_type : mac_ 04:43:47 INFO - Initialising browser for kraken test... 04:43:47 INFO - TEST-INFO | started process 1843 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmpIiTHPJ/profile http://localhost:49244/getInfo.html) 04:43:48 INFO - PROCESS | 1843 | 1483274628103 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 04:43:50 INFO - PROCESS | 1843 | __metrics Screen width/height:1600/1200 04:43:50 INFO - PROCESS | 1843 | colorDepth:24 04:43:50 INFO - PROCESS | 1843 | Browser inner width/height: 1024/689 04:43:50 INFO - PROCESS | 1843 | __metrics 04:43:50 INFO - PROCESS | 1843 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:43:50 INFO - PROCESS | 1843 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:43:50 INFO - TEST-INFO | 1843: exit 0 04:43:50 INFO - Browser initialized. 04:43:50 INFO - Running cycle 1/1 for kraken test... 04:43:50 INFO - TEST-INFO | started process 1845 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmpIiTHPJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:44:02 INFO - PROCESS | 1845 | RSS: Main: 234082304 04:44:02 INFO - PROCESS | 1845 | 04:44:05 INFO - PROCESS | 1845 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 04:44:06 INFO - PROCESS | 1845 | RSS: Main: 286318592 04:44:06 INFO - PROCESS | 1845 | 04:44:09 INFO - PROCESS | 1845 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 04:44:09 INFO - PROCESS | 1845 | RSS: Main: 286023680 04:44:09 INFO - PROCESS | 1845 | 04:44:12 INFO - PROCESS | 1845 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 04:44:13 INFO - PROCESS | 1845 | RSS: Main: 378589184 04:44:13 INFO - PROCESS | 1845 | 04:44:15 INFO - PROCESS | 1845 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 04:44:16 INFO - PROCESS | 1845 | RSS: Main: 281804800 04:44:16 INFO - PROCESS | 1845 | 04:44:18 INFO - PROCESS | 1845 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:44:18 INFO - PROCESS | 1845 | RSS: Main: 285782016 04:44:18 INFO - PROCESS | 1845 | 04:44:21 INFO - PROCESS | 1845 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 04:44:22 INFO - PROCESS | 1845 | RSS: Main: 328925184 04:44:22 INFO - PROCESS | 1845 | 04:44:25 INFO - PROCESS | 1845 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 04:44:25 INFO - PROCESS | 1845 | RSS: Main: 329138176 04:44:25 INFO - PROCESS | 1845 | 04:44:28 INFO - PROCESS | 1845 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 04:44:29 INFO - PROCESS | 1845 | RSS: Main: 328593408 04:44:29 INFO - PROCESS | 1845 | 04:44:30 INFO - PROCESS | 1845 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:44:31 INFO - PROCESS | 1845 | RSS: Main: 304009216 04:44:31 INFO - PROCESS | 1845 | 04:44:33 INFO - PROCESS | 1845 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:44:34 INFO - PROCESS | 1845 | RSS: Main: 305905664 04:44:34 INFO - PROCESS | 1845 | 04:44:36 INFO - PROCESS | 1845 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:44:37 INFO - PROCESS | 1845 | RSS: Main: 307691520 04:44:37 INFO - PROCESS | 1845 | 04:44:39 INFO - PROCESS | 1845 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:44:40 INFO - PROCESS | 1845 | RSS: Main: 323338240 04:44:40 INFO - PROCESS | 1845 | 04:44:43 INFO - PROCESS | 1845 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:44:44 INFO - PROCESS | 1845 | RSS: Main: 317952000 04:44:44 INFO - PROCESS | 1845 | 04:44:46 INFO - PROCESS | 1845 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 04:44:46 INFO - PROCESS | 1845 | RSS: Main: 361644032 04:44:46 INFO - PROCESS | 1845 | 04:44:46 INFO - PROCESS | 1845 | __start_tp_report 04:44:46 INFO - PROCESS | 1845 | _x_x_mozilla_page_load 04:44:46 INFO - PROCESS | 1845 | _x_x_mozilla_page_load_details 04:44:46 INFO - PROCESS | 1845 | |i|pagename|runs| 04:44:46 INFO - PROCESS | 1845 | |0;ai-astar;114;114;113;113;112;113;113;114;112;113 04:44:46 INFO - PROCESS | 1845 | |1;audio-beat-detection;138;109;108;142;130;111;109;108;127;108 04:44:46 INFO - PROCESS | 1845 | |2;audio-dft;185;183;185;185;183;186;185;182;183;178 04:44:46 INFO - PROCESS | 1845 | |3;audio-fft;79;58;58;85;60;59;59;57;74;59 04:44:46 INFO - PROCESS | 1845 | |4;audio-oscillator;91;70;85;69;69;68;80;91;97;112 04:44:46 INFO - PROCESS | 1845 | |5;imaging-gaussian-blur;87;84;87;84;86;84;87;84;87;86 04:44:46 INFO - PROCESS | 1845 | |6;imaging-darkroom;153;164;95;96;96;96;97;95;96;96 04:44:46 INFO - PROCESS | 1845 | |7;imaging-desaturate;91;129;85;86;133;137;84;87;84;87 04:44:46 INFO - PROCESS | 1845 | |8;json-parse-financial;54;55;55;55;56;57;55;56;56;57 04:44:46 INFO - PROCESS | 1845 | |9;json-stringify-tinderbox;64;73;66;72;77;71;70;70;70;70 04:44:46 INFO - PROCESS | 1845 | |10;stanford-crypto-aes;87;89;88;87;86;85;95;105;84;90 04:44:46 INFO - PROCESS | 1845 | |11;stanford-crypto-ccm;140;155;150;149;133;147;143;150;184;152 04:44:46 INFO - PROCESS | 1845 | |12;stanford-crypto-pbkdf2;175;172;226;207;171;168;170;169;172;170 04:44:46 INFO - PROCESS | 1845 | |13;stanford-crypto-sha256-iterative;67;68;79;80;84;85;71;63;68;66 04:44:46 INFO - PROCESS | 1845 | __end_tp_report 04:44:46 INFO - PROCESS | 1845 | __start_cc_report 04:44:46 INFO - PROCESS | 1845 | _x_x_mozilla_cycle_collect,1950 04:44:46 INFO - PROCESS | 1845 | __end_cc_report 04:44:46 INFO - PROCESS | 1845 | __startTimestamp1483274686382__endTimestamp 04:44:46 INFO - PROCESS | 1845 | 04:44:46 INFO - PROCESS | 1845 | ------- Summary: start ------- 04:44:46 INFO - PROCESS | 1845 | Number of tests: 14 04:44:46 INFO - PROCESS | 1845 | 04:44:46 INFO - PROCESS | 1845 | [#0] ai-astar Cycles:10 Average:113.10 Median:113.00 stddev:0.74 (0.7%) stddev-sans-first:0.71 04:44:46 INFO - PROCESS | 1845 | Values: 114.0 114.0 113.0 113.0 112.0 113.0 113.0 114.0 112.0 113.0 04:44:46 INFO - PROCESS | 1845 | 04:44:46 INFO - PROCESS | 1845 | [#1] audio-beat-detection Cycles:10 Average:119.00 Median:119.00 stddev:13.75 (11.6%) stddev-sans-first:12.75 04:44:46 INFO - PROCESS | 1845 | Values: 138.0 109.0 108.0 142.0 130.0 111.0 109.0 108.0 127.0 108.0 04:44:46 INFO - PROCESS | 1845 | 04:44:46 INFO - PROCESS | 1845 | [#2] audio-dft Cycles:10 Average:183.50 Median:185.00 stddev:2.32 (1.3%) stddev-sans-first:2.40 04:44:46 INFO - PROCESS | 1845 | Values: 185.0 183.0 185.0 185.0 183.0 186.0 185.0 182.0 183.0 178.0 04:44:46 INFO - PROCESS | 1845 | 04:44:46 INFO - PROCESS | 1845 | [#3] audio-fft Cycles:10 Average:64.80 Median:59.50 stddev:10.39 (17.5%) stddev-sans-first:9.67 04:44:46 INFO - PROCESS | 1845 | Values: 79.0 58.0 58.0 85.0 60.0 59.0 59.0 57.0 74.0 59.0 04:44:46 INFO - PROCESS | 1845 | 04:44:46 INFO - PROCESS | 1845 | [#4] audio-oscillator Cycles:10 Average:83.20 Median:82.50 stddev:14.77 (17.9%) stddev-sans-first:15.39 04:44:46 INFO - PROCESS | 1845 | Values: 91.0 70.0 85.0 69.0 69.0 68.0 80.0 91.0 97.0 112.0 04:44:46 INFO - PROCESS | 1845 | 04:44:46 INFO - PROCESS | 1845 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.50 stddev:1.43 (1.7%) stddev-sans-first:1.42 04:44:46 INFO - PROCESS | 1845 | Values: 87.0 84.0 87.0 84.0 86.0 84.0 87.0 84.0 87.0 86.0 04:44:46 INFO - PROCESS | 1845 | 04:44:46 INFO - PROCESS | 1845 | [#6] imaging-darkroom Cycles:10 Average:108.40 Median:96.00 stddev:26.54 (27.6%) stddev-sans-first:22.72 04:44:46 INFO - PROCESS | 1845 | Values: 153.0 164.0 95.0 96.0 96.0 96.0 97.0 95.0 96.0 96.0 04:44:46 INFO - PROCESS | 1845 | 04:44:46 INFO - PROCESS | 1845 | [#7] imaging-desaturate Cycles:10 Average:100.30 Median:85.50 stddev:22.73 (26.6%) stddev-sans-first:23.86 04:44:46 INFO - PROCESS | 1845 | Values: 91.0 129.0 85.0 86.0 133.0 137.0 84.0 87.0 84.0 87.0 04:44:46 INFO - PROCESS | 1845 | 04:44:46 INFO - PROCESS | 1845 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:0.97 (1.7%) stddev-sans-first:0.83 04:44:46 INFO - PROCESS | 1845 | Values: 54.0 55.0 55.0 55.0 56.0 57.0 55.0 56.0 56.0 57.0 04:44:46 INFO - PROCESS | 1845 | 04:44:46 INFO - PROCESS | 1845 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:70.50 stddev:3.56 (5.1%) stddev-sans-first:2.96 04:44:46 INFO - PROCESS | 1845 | Values: 64.0 73.0 66.0 72.0 77.0 71.0 70.0 70.0 70.0 70.0 04:44:46 INFO - PROCESS | 1845 | 04:44:46 INFO - PROCESS | 1845 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:87.50 stddev:6.22 (7.1%) stddev-sans-first:6.53 04:44:46 INFO - PROCESS | 1845 | Values: 87.0 89.0 88.0 87.0 86.0 85.0 95.0 105.0 84.0 90.0 04:44:46 INFO - PROCESS | 1845 | 04:44:46 INFO - PROCESS | 1845 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:150.00 stddev:13.47 (9.0%) stddev-sans-first:13.76 04:44:46 INFO - PROCESS | 1845 | Values: 140.0 155.0 150.0 149.0 133.0 147.0 143.0 150.0 184.0 152.0 04:44:46 INFO - PROCESS | 1845 | 04:44:46 INFO - PROCESS | 1845 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.00 Median:172.00 stddev:19.84 (11.5%) stddev-sans-first:20.96 04:44:46 INFO - PROCESS | 1845 | Values: 175.0 172.0 226.0 207.0 171.0 168.0 170.0 169.0 172.0 170.0 04:44:46 INFO - PROCESS | 1845 | 04:44:46 INFO - PROCESS | 1845 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:75.00 stddev:8.09 (10.8%) stddev-sans-first:8.27 04:44:46 INFO - PROCESS | 1845 | Values: 67.0 68.0 79.0 80.0 84.0 85.0 71.0 63.0 68.0 66.0 04:44:46 INFO - PROCESS | 1845 | -------- Summary: end -------- 04:44:46 INFO - PROCESS | 1845 | 04:44:47 INFO - TEST-INFO | 1845: exit 0 04:44:47 INFO - TEST-OK | kraken | took 59695ms 04:44:47 INFO - SUITE-END | took 1179s 04:44:47 INFO - Completed test suite (00:19:39) 04:44:47 INFO - javascript benchmark 04:44:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3351.8659253629858, 3363.915902102449, 3383.289509203067, 3399.0142858571016, 3429.108431807729, 5514.889702205954, 5552.611317207797, 5762.078067509199, 5775.566832487564, 5804.332501762298, 5601.579881508883, 5734.225879506265, 5826.796062137826, 5829.679367634779, 5843.2695913010875, 4777.450593181794, 4961.900761984776, 5163.741812909339, 5221.973890130544, 5229.111051121318, 4598.793884919718, 5319.148936170213, 5333.519983201511, 5351.277577527042, 5405.891882162355, 5140.3831540215415, 5279.41926388097, 5309.230161626581, 5316.9202461963105, 5342.118550439192, 5163.225516172567, 5176.378834539879, 5240.502152295564, 5257.237700533401, 5273.499017593323, 5210.843674689728, 5234.581233501311, 5274.05067087924, 5283.260343551906, 5293.285406470116, 4774.0, 5746.138079288098, 5746.712664366765, 5749.568782341299, 5763.250777398953, 6132.938670613281, 6149.477294429978, 6252.906206406908, 6275.937240627581, 6285.214348206474, 6154.661493617849, 6200.81397558074, 6237.469815065725, 6240.157578726859, 6280.403361680633, 5848.976429124886, 5849.6197747146725, 5852.268466441695, 5857.882842343214, 5894.675792831393, 6152.3540028297275, 6297.590656607306, 6302.684865756693, 6304.936950630435, 6312.715927783261, 6033.78881739137, 6100.145979562864, 6113.755449782047, 6116.510679145657, 6176.505879529627, 6130.122981477994, 6204.0, 6238.1890354254565, 6257.2491301044165, 6261.96869015652, 6120.479759220516, 6120.660465672121, 6157.0456579230295, 6157.476614487729, 6219.098230756515, 6266.592671476339, 6266.62400255977, 6275.717592708339, 6292.528060395489, 6313.116163736989, 4887.486813884562, 4939.234418665113, 5017.799288028511, 5019.222020586815, 5038.546530812245, 4441.533638967927, 4488.730676159441, 4508.45898492183, 4509.661775366861, 4511.842085526991, 5786.623869448473, 5914.497267732291, 5915.704214789243, 5952.285725712855, 5979.222701048923, 6221.3156552779155, 6238.469730072995, 6244.313125556185, 6259.311475737665, 6263.592866463665, 5904.439078287556, 5906.822795316061, 5931.258592675916, 5931.673757943268, 5969.403059693996, 5327.121025030827, 5357.223202635596, 5370.221317908881, 5404.621676482609, 5418.945810541923, 6212.502999760009, 6214.223222097147, 6267.310595834363, 6343.809685709436, 6426.582272152295, 6155.076738489262, 6208.192934918432, 6217.067439884054, 6217.4715149212825, 6241.469475094668, 6411.230652321751, 6421.486281097407, 6424.068510066108, 6424.839379015562, 6427.646479443675, 6079.604825686316, 6106.915739268539, 6120.386183820725, 6137.867325793431, 6142.775043102407, 4780.784864681028, 6273.905891411541, 6276.027215781562, 6283.088952101828, 6285.59143655661, 6090.878182436415, 6136.20229370179, 6175.505959523138, 6180.598261113013, 6184.628922264678, 5998.280206375263, 6056.091586262096, 6083.7870674526175, 6095.847603809941, 6119.357467465852, 5795.24661793981, 6031.577789554689, 6038.305594856648, 6038.969805150946, 6101.542384321282, 5323.840284791462, 5881.852953676193, 6046.1535385045245, 6121.051237058263, 6141.692915354304, 5869.765209391577, 5934.139549765174, 5952.464278214981, 6016.187814645142, 6115.663638499926], "value": 5711.837848409667, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.92138278502483, 473.85310553728334, 527.3618921105463, 536.6163193316776, 613.2793967088672, 37293.81353093234, 39718.602813971855, 39839.800800996025, 41751.0, 41843.372349414705, 40916.181676366454, 41325.38011929816, 41352.37971430431, 41357.0, 41366.58633413665, 8431.0, 8664.480131192116, 9231.123043310892, 9452.542338991843, 9453.291003174754, 10964.616238431656, 11278.041366483858, 11291.717707057349, 11404.486798094105, 11455.828162577567, 11171.66485005451, 11240.381779002193, 11242.269252498601, 11254.043406310433, 11264.662060138167, 11290.887091129107, 11360.431978401048, 11363.090952723762, 11367.658970230907, 11385.54457821686, 9527.761805954837, 11155.219134660578, 11235.21353505255, 11244.662660120128, 11246.0, 38007.42988855169, 38203.617963820565, 38215.426768598496, 38306.23387532234, 38338.04154896122, 3923.156521347924, 3925.8625948091967, 4024.9798751006056, 4028.395740638898, 4112.835486580533, 28948.128667204764, 29744.55383169254, 29811.850940745375, 30116.24709382261, 30140.24649383761, 3133.3263348380206, 3263.1352691536763, 3273.2798784267425, 3335.6497567755277, 3338.43246648068, 456815.0, 457050.0, 457680.0, 457833.0, 459083.0, 54086.0, 54359.0, 54379.0, 54422.18366724502, 54574.727126364116, 57423.42576574264, 57510.42489575134, 57788.71105644445, 58040.356771459024, 58129.4187058124, 15078.944473886722, 15294.694106117815, 15311.617209569627, 15329.616759580877, 15335.539933802003, 10693.518791654395, 10976.780464390667, 11007.339559626287, 11623.418829058513, 11912.642620721392, 14711.337989790485, 14920.850791492163, 14940.879434042274, 14953.177575233467, 15046.097234165985, 5957.553183511254, 6942.791716248521, 6943.79168624942, 6994.230634630187, 7044.894326585106, 736.6905899522213, 865.9393842431095, 890.4167770110588, 909.4588719711761, 921.801812610292, 27765.583516247272, 29082.41835163327, 29328.413431731246, 29582.0, 29621.259468513028, 1038.3458421194598, 1050.1598721023151, 1083.8916108389099, 1089.5641743302742, 1198.4007996002, 12006.939965300118, 13772.48502664726, 14632.634184145269, 15059.774103388458, 15423.691526169414, 488421.0, 580290.0, 581018.0, 581558.0, 584032.0, 55833.441665582824, 55950.160747588816, 55991.72004139953, 56111.0, 56121.71939140278, 1370.294298436306, 1402.5161319344809, 1407.9014468987073, 1415.4974983880575, 1425.836028856674, 27502.0, 28288.434231314848, 28514.0, 28607.570886437137, 29125.417491649623, 46506.767466162455, 46976.0, 47107.057858841945, 47121.057578848915, 47128.29307560458, 486743.0, 487966.8838083524, 489920.0, 491631.0, 492920.0, 32173.517397239528, 33196.67213311537, 36848.0, 36910.8154459226, 38208.617913820504, 43937.78031109824, 44020.55979440165, 44387.778061109486, 44498.3325250128, 45245.0, 36582.26835463329, 37056.55321361454, 37124.25751485009, 37510.43734344042, 37939.05152371102, 36481.452778207815, 36662.0, 37491.6250837499, 37660.62339376681, 37806.24387512289, 28424.28939276452, 35284.647153529164, 35882.0, 36604.81697591495, 36714.0, 30867.38265234638, 30873.0, 31997.680023200403, 32009.199770005936, 33559.66440335663, 22844.31467055991, 23651.0, 23957.0, 24457.0, 24659.78061974431], "value": 19538.03093526057, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.9641987591301, 371.58753783300534, 373.74398537002156, 382.11730901617256, 399.7641391578968, 14754.188519631429, 14973.176475293854, 15565.999999999985, 19775.208991640317, 20330.39008829734, 19094.522636934085, 19563.608727825434, 20075.899620501914, 20365.083571239295, 20378.796212037876, 5600.747966341524, 5754.65472071675, 5794.188813566104, 5797.043487824525, 5797.36229014808, 4931.852044438672, 6315.368463153671, 6404.071409645599, 6455.548111632188, 6455.741770329181, 6131.3562075982045, 6271.560990730651, 6377.617342959414, 6402.93597064028, 6425.421712045938, 6325.778597749079, 6367.6497792621385, 6386.42522173002, 6388.8083357499345, 6391.073294372313, 5256.8685782855355, 5322.547583455411, 5554.0, 5567.66594004361, 5574.275344205227, 25175.118870839615, 25221.495570088497, 25247.0, 25359.746402536108, 25530.617040744404, 2574.4464940037983, 2654.5487267164626, 2675.2241849863517, 2718.184544636621, 2752.683441404244, 14169.716605667933, 14848.406063757437, 15091.320890559842, 15402.922985385114, 15502.9224853875, 1822.8906265624105, 1830.2313028528051, 1852.740616313717, 1874.259667431357, 1899.705545640428, 114804.42597787041, 119333.0, 119340.0, 119881.0, 121390.0, 10728.141748660088, 10828.945855270673, 10838.40388778609, 11368.488418021208, 11406.42967851604, 11061.612843550438, 14280.85719142816, 14451.0, 14597.562073137822, 14601.904857135758, 6831.487638427137, 8150.225728555778, 8231.711890083818, 8271.54506502136, 8291.543965081857, 7703.189750408649, 7781.649825757854, 8057.153998830156, 8172.223638754309, 8175.305099066527, 4375.0, 4428.158649856517, 4443.755593442392, 4590.816367345269, 4591.908161836812, 1884.2839720905968, 1930.5752734398409, 1935.5451468904796, 1936.3803582853352, 1940.4469726127986, 837.9483747896351, 852.7271273192518, 934.2619330728784, 980.7940332530248, 986.7829077602916, 19232.519187020436, 20699.0, 20759.68860467094, 20814.0, 20852.895735521226, 836.5566249887569, 935.1490143969091, 954.2938225712923, 978.9021097890154, 1032.3289861590026, 8733.0, 10873.184511161537, 10906.836397454044, 10935.343879367263, 10971.780564388828, 320545.0, 321133.0, 387771.4674239109, 564977.0, 569883.0, 121955.0, 122315.0, 122596.38701806434, 124675.0, 125285.0, 925.3383830561181, 978.4862946952907, 990.1138481059555, 990.5393991793851, 994.6916455898549, 18027.819721802614, 18207.0, 18423.355182568546, 18533.629327413644, 18618.534536636693, 52887.0, 52914.47085529096, 53534.70212117128, 54041.0, 54281.45718542922, 76463.0, 77229.91080356723, 77976.0, 78239.21760782319, 79023.0, 7889.723859664884, 8077.7576672699915, 8196.877046844422, 8201.30288925448, 8285.461445006054, 11491.885081149416, 12266.693332666755, 12692.85764281219, 12737.55418560346, 12756.87243127594, 9046.502442365732, 10927.781444371227, 11203.031196732709, 11212.15908806859, 11243.212975091665, 10056.748581285234, 10152.137068348977, 10375.844362334721, 10926.524919135814, 10966.287191332538, 11250.549978000789, 11382.0, 11463.828042579535, 11506.539738410369, 11538.807626550928, 7837.451378403685, 7901.130875603794, 7983.920160798318, 8050.758477245692, 8151.150862026045, 8113.269805717514, 9413.0, 9893.802123957625, 10665.9466702666, 11131.332120072822], "value": 9468.368445750348, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1307.921524708518, 1403.670137517683, 1405.409727914276, 1424.1597457500077, 1426.9928650356746, 7323.853522929545, 7491.66287517061, 7723.227677232274, 8244.0, 8291.751247462586, 8093.838123237531, 8195.631196596152, 8201.179882011797, 8203.466774659642, 8205.58972051398, 2046.9385918422472, 2274.1358283852114, 2315.9925432436858, 2317.8377513574055, 2321.106374045996, 2132.893355332235, 2255.5939930812447, 2331.9650205246935, 2333.8132949364012, 2347.7182738071456, 2252.335561009506, 2272.7613600571867, 2281.0191617604423, 2281.817454603636, 2282.6005449046434, 2283.862968221904, 2298.310506847956, 2303.1823702585507, 2303.527776805751, 2307.561563302984, 2281.7946384825277, 2311.5492478966607, 2315.22439982606, 2315.490592069742, 2325.430269583946, 8207.302379297767, 8216.712415065504, 8262.008558972962, 8342.541160236246, 8344.958275208646, 4973.825916092919, 5121.231815227746, 5144.794208231666, 5146.536811686966, 5159.613029022838, 2963.733264006228, 3287.7862938909134, 3443.724502039831, 3482.3905816482015, 3516.1736991806906, 1151.0733859243305, 1207.2153100484668, 1208.0, 1242.273270136971, 1262.1291308996763, 10478.476076196159, 10601.41692206936, 10602.0, 10609.045185933302, 10637.0, 4325.264705000158, 4433.467983841895, 4497.190505708938, 4508.368828363966, 4518.9548104518535, 8546.31629469641, 9233.769155771159, 9248.630054797868, 9254.491002994766, 9299.581518831668, 5097.898042039138, 5120.18077107661, 5121.078205922895, 5124.0, 5138.100832354323, 4441.422615060022, 4623.884402889955, 4657.510961349009, 4677.579017888406, 4694.624430045588, 3687.5574931007914, 3715.0898029982827, 3747.0, 3784.791836449022, 3793.3740932745786, 1238.8265642810013, 1248.1200753468963, 1249.2504497301547, 1252.2611659121162, 1265.5886836778084, 843.1694780641063, 868.5830801215452, 875.7285241575005, 908.6819613135482, 923.7599418668344, 5211.140161873249, 5319.893602127936, 5469.206964990134, 5542.057850165482, 5611.298587676541, 519.7037688517509, 537.5645726961174, 538.2921458282347, 543.9129739241781, 545.3946119807011, 3459.6540345965204, 3494.545709057857, 3519.419295816213, 3529.5411596492804, 3593.1735700788963, 9502.47736374506, 9781.217502599775, 9889.752756181153, 9921.950390248003, 9924.603015879284, 9436.811263774824, 9872.1608663263, 9872.901270987342, 9888.9616590257, 9891.653792117242, 5815.592908496449, 7623.237676232332, 7664.846703066019, 7672.0409948756405, 7692.46152769312, 4337.956620433755, 4803.471618122074, 5235.319408476874, 5355.652717051843, 5416.485433883775, 6862.936244882051, 8517.95741021291, 8917.28661707075, 8954.865677014719, 8980.416272942499, 9076.636934522545, 9162.26701863836, 9272.119148680864, 9294.814103717752, 9297.07029297065, 6491.28595854465, 6668.466522678271, 6697.129373181455, 6802.251752307143, 6980.720771169096, 7802.0, 7823.022122234721, 7896.7236146734585, 7901.972743543302, 7902.091259505, 5996.850078748066, 6968.059311992816, 6998.6500674967065, 7072.893906591508, 7139.178994415709, 6724.731010759515, 6825.283345248876, 6834.45324374039, 7003.824904377431, 7006.439484841098, 5617.662940223599, 6051.789642071515, 6147.44672979434, 6295.874082518416, 6324.30432652417, 4714.834980775793, 4715.952840471551, 4717.127331443694, 4742.976285118552, 4861.124997500484, 4643.210654188729, 4644.094401591792, 4720.079584481131, 4801.5438533339275, 4924.064427758714], "value": 4240.19685580803, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.64089987313065, 351.5640605648994, 357.4548813060083, 368.68477451778745, 368.71608861176895, 5121.206213036985, 5278.498542638453, 5329.561018525001, 5343.465653434655, 5374.679067722956, 4887.780049897754, 4977.377827771528, 4983.626228032903, 4990.1556113015595, 5138.972205558894, 4175.770332631704, 4208.600182982619, 4215.85244516442, 4231.788410579458, 4240.151969606076, 3808.238352329531, 3836.5204349456317, 4201.852935147271, 4224.943764058985, 4240.194363071022, 3902.540670070102, 4062.7359221650645, 4148.979255103735, 4153.418521407006, 4179.498460184782, 3284.458064419368, 3526.806025668587, 3997.5003124609425, 4107.54816970133, 4120.814563344657, 3161.7944833585743, 3239.3521295740948, 3510.3681337359267, 3638.362736768465, 3648.5372373647683, 4401.713888597263, 4883.145449546315, 4886.169351210267, 4970.7324632218915, 5175.870603234912, 3555.7732582259077, 3742.4760533525327, 3871.593482684306, 3898.577019387931, 4562.745245057603, 5129.7435128243815, 5165.535101840815, 5174.0, 5435.918461223085, 5528.723563821793, 3266.591676040495, 3779.6787273081823, 3793.886183414502, 3797.373433383516, 3807.1243613968663, 5067.961067981092, 5567.49892509672, 5756.136579513065, 5771.538276937835, 5811.564132690065, 3832.0, 3847.5575308839843, 3849.095462566295, 3859.9035024124623, 4030.7581545107387, 3700.389435743126, 3908.863189788344, 3916.2754890345377, 3925.5878132795647, 3930.371140617545, 3958.91129939269, 4269.3595960605535, 4448.35498852671, 4530.048689775184, 4564.0, 4767.831881189061, 5535.778568857281, 5551.944480555224, 5573.191887176417, 5580.791045149055, 1109.0074383426788, 1222.9877701222874, 1253.6552448076852, 1253.72746662138, 1256.604169686564, 775.9718373155479, 794.8950958168151, 818.7461886815106, 822.7284995951321, 841.4068082002174, 4551.520755754393, 4960.454350021495, 5006.723285562159, 5015.623828212899, 5058.519440653132, 4511.714161464032, 4613.700109492872, 4939.851804445872, 5239.0, 5347.893042139135, 4410.845620403271, 4710.86727326331, 4733.5503127202865, 4945.799119391836, 5014.89970200594, 2619.2404202781363, 2691.757741803247, 2703.7431444012786, 2718.3340081680117, 2828.5050116229577, 4722.70125715431, 5153.922691159635, 5505.476979686923, 5552.639078459887, 5576.219329293902, 4872.439669437989, 5151.922721159186, 5181.3782346118705, 5395.81114660985, 5510.614257001971, 5311.548518375981, 5368.295875845654, 5499.058003967858, 5509.834704958857, 5562.666240025612, 4630.351750754964, 4808.0383923214795, 5013.197888337849, 5355.920345801102, 5363.731813409236, 4430.0, 5202.271681964604, 5259.921101183409, 5584.235803277404, 5632.352279487911, 5959.106134079922, 5968.552358573038, 6016.548758843017, 6020.54845886569, 6043.3352331244405, 3549.18368775178, 3811.58072612018, 3845.4808600839647, 3849.210911763138, 3869.342211824053, 5624.74688639004, 5657.575681823797, 5715.39988301239, 5725.621883088831, 5739.938111449395, 5512.614117011771, 5517.944820551743, 5556.666600004012, 5570.24801651788, 5575.661841158174, 4379.737215767064, 5464.0, 5518.53092487127, 5520.447955204448, 5584.301962254718, 4380.934285985649, 4529.818807247805, 5281.762320695655, 5409.432009639088, 5452.563794896478, 5146.871328216825, 5310.415854256106, 5449.100898351728, 5481.479259470344, 5493.423190564933, 4605.0, 4874.4150701916, 4937.77779999908, 5396.487333703292, 5556.861078473071], "value": 3984.451943072797, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.5328156775745, 716.1442076718324, 761.1361105145661, 796.2323641515834, 796.5643831821014, 14644.926775366122, 15174.393024279016, 15562.988405753633, 15568.37726490939, 15589.376424943017, 14077.114191590725, 14206.36071376788, 14236.928815355936, 14262.50081247154, 14274.286285685726, 675.8884784010633, 700.1807884774826, 701.2251462134344, 828.6478246745139, 846.4075147396816, 712.4087007783553, 854.7692123126752, 861.6553378648528, 865.2991077227434, 867.1935100356666, 822.4201937633953, 909.917198533746, 920.5627327019679, 921.9815603687922, 925.6852670092168, 916.4730280088942, 919.3197034194715, 919.8804155459815, 921.1019256225227, 922.3451349541833, 524.2581746828233, 541.1612001397857, 649.0426620734427, 672.146374104889, 692.2143367278126, 14684.926575367163, 14728.705425891529, 14751.041182323115, 14760.852391476055, 14771.965962382637, 11192.608258710907, 13341.332933353391, 13388.0, 13512.391942362618, 13559.864401355959, 10674.306170098918, 10976.0, 11266.155038372155, 11266.66200014001, 12301.507939682491, 4355.629771469429, 4391.780410979438, 4431.689781715281, 4471.8658440246845, 4571.422859113601, 94922.0, 103471.48264258706, 104306.95693043125, 105641.0, 107829.92170078277, 10547.789044219226, 10580.57677692899, 10600.946995264974, 10753.408562528979, 10787.56849726018, 18785.624287514172, 19446.222151113798, 20615.072321745556, 20684.276050338165, 20690.17239310411, 7213.0, 7306.634668266565, 7376.299251571092, 7457.627118644046, 7482.401407887465, 4136.860642118771, 4172.687048471377, 4343.961892968776, 4352.273691249025, 4389.531127302372, 3483.1814523586936, 3565.6612621800887, 3582.355176068281, 3644.1436262478296, 3644.927101458009, 608.9330173680892, 645.8332295149705, 648.6410969019896, 657.907892894995, 673.8079647300495, 467.7918326344744, 654.3379815269258, 665.311402698205, 666.3170250493187, 667.1993607670814, 8804.955975220211, 9165.31260155491, 9317.068293170629, 9393.5303234838, 9522.571484283224, 638.8498702804837, 651.7653644687908, 674.7197736423931, 676.4106722572726, 682.2120450879243, 4355.891102722458, 4448.5551444855255, 4507.3689683444345, 4541.500434952152, 4584.724916505021, 69635.0, 70545.0, 86989.0, 90370.0, 92238.07761922429, 85528.57235713906, 86676.0, 87379.0, 88022.23955520854, 88995.22009559773, 76578.0, 77659.0, 79685.60157199293, 79827.4034519318, 80719.59640201762, 8395.95802020986, 8692.521911294687, 8709.738707838775, 8711.21599056088, 8957.0, 36577.62245454037, 52069.0, 52289.0, 52569.68575785636, 53857.46142538524, 60023.7995240084, 69947.65026174836, 70107.0, 70278.29721702718, 73046.63476682795, 11066.723331916766, 11245.606403775828, 11339.376334301363, 11437.19939604253, 11651.88348116508, 12470.937645311715, 12892.742145156857, 13085.803712944504, 13277.35946775018, 13316.40076196554, 11843.0, 11867.287962722265, 12175.048076444207, 12380.690482738004, 12406.007519398247, 11789.764204715686, 11835.763284734085, 11955.0, 11959.641210763692, 12311.630651080482, 11630.0, 12053.096017798876, 12155.209911355734, 12289.385530723606, 12330.568430104904, 9816.950915245377, 9868.950655246965, 9869.802603947737, 10332.690019299433, 10355.223358248011, 9486.620535178516, 9527.71130085499, 9604.587384692097, 9616.663416780659, 9714.077162669535], "value": 7156.162812241897, "unit": "score"}], "name": "dromaeo_css", "value": 7096.909747506571, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 113.0, 112.0, 113.0, 113.0, 114.0, 112.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 109.0, 108.0, 142.0, 130.0, 111.0, 109.0, 108.0, 127.0, 108.0], "value": 119.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 185.0, 185.0, 183.0, 186.0, 185.0, 182.0, 183.0, 178.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 58.0, 85.0, 60.0, 59.0, 59.0, 57.0, 74.0, 59.0], "value": 64.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 85.0, 69.0, 69.0, 68.0, 80.0, 91.0, 97.0, 112.0], "value": 83.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 86.0, 84.0, 87.0, 84.0, 87.0, 86.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 164.0, 95.0, 96.0, 96.0, 96.0, 97.0, 95.0, 96.0, 96.0], "value": 108.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 129.0, 85.0, 86.0, 133.0, 137.0, 84.0, 87.0, 84.0, 87.0], "value": 100.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 55.0, 56.0, 57.0, 55.0, 56.0, 56.0, 57.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 73.0, 66.0, 72.0, 77.0, 71.0, 70.0, 70.0, 70.0, 70.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 88.0, 87.0, 86.0, 85.0, 95.0, 105.0, 84.0, 90.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 155.0, 150.0, 149.0, 133.0, 147.0, 143.0, 150.0, 184.0, 152.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 172.0, 226.0, 207.0, 171.0, 168.0, 170.0, 169.0, 172.0, 170.0], "value": 180.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 79.0, 80.0, 84.0, 85.0, 71.0, 63.0, 68.0, 66.0], "value": 73.1, "unit": "score"}], "name": "kraken", "value": 1476.7999999999997, "alertThreshold": 2.0}]} 04:44:47 INFO - Return code: 0 04:44:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:44:47 INFO - # TBPL SUCCESS # 04:44:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:44:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:44:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:44:47 INFO - 2017-01-01 04:44:47.721 screenresolution[1855:10678] starting screenresolution argv=screenresolution get 04:44:47 INFO - 2017-01-01 04:44:47.723 screenresolution[1855:10678] Display 0: 1600x1200x32@60 04:44:47 INFO - 2017-01-01 04:44:47.728 screenresolution[1856:10681] starting screenresolution argv=screenresolution list 04:44:47 INFO - Available Modes on Display 0 04:44:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:44:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:44:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:44:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:44:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:44:47 INFO - Intel Iris: 04:44:47 INFO - Chipset Model: Intel Iris 04:44:47 INFO - Type: GPU 04:44:47 INFO - Bus: Built-In 04:44:47 INFO - VRAM (Dynamic, Max): 1536 MB 04:44:47 INFO - Vendor: Intel (0x8086) 04:44:47 INFO - Device ID: 0x0a2e 04:44:47 INFO - Revision ID: 0x0009 04:44:47 INFO - Displays: 04:44:47 INFO - Display: 04:44:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:44:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:44:47 INFO - Display Serial Number: 1600x1200 60 04:44:47 INFO - Main Display: Yes 04:44:47 INFO - Mirror: Off 04:44:47 INFO - Online: Yes 04:44:47 INFO - Rotation: Supported 04:44:47 INFO - Return code: 0 04:44:47 INFO - Running post-action listener: _resource_record_post_action 04:44:47 INFO - [mozharness: 2017-01-01 12:44:47.951344Z] Finished run-tests step (success) 04:44:47 INFO - Running post-run listener: _resource_record_post_run 04:44:48 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32136192; Write bytes: 555270144; Read time: 322; Write time: 1730 04:44:48 INFO - TinderboxPrint: CPU usage
53.8% 04:44:48 INFO - TinderboxPrint: I/O read bytes / time
32,136,192 / 322 04:44:48 INFO - TinderboxPrint: I/O write bytes / time
555,270,144 / 1,730 04:44:48 INFO - TinderboxPrint: CPU idle
2,221.2 (46.2%) 04:44:48 INFO - TinderboxPrint: CPU system
965.9 (20.1%) 04:44:48 INFO - TinderboxPrint: CPU user
1,622.5 (33.7%) 04:44:48 INFO - TinderboxPrint: Swap in / out
277,716,992 / 0 04:44:48 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 231074304; Write bytes: 223346688; Read time: 16426; Write time: 404 04:44:48 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 31202304; Write bytes: 304975872; Read time: 308; Write time: 1274 04:44:48 INFO - Running post-run listener: _upload_blobber_files 04:44:48 INFO - Blob upload gear active. 04:44:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:44:48 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:44:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:44:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:44:48 INFO - (blobuploader) - INFO - Open directory for files ... 04:44:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:44:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:44:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:44:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:44:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:44:49 INFO - (blobuploader) - INFO - Done attempting. 04:44:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:44:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:44:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:44:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:44:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:44:50 INFO - (blobuploader) - INFO - Done attempting. 04:44:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:44:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:44:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:44:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:44:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:44:51 INFO - (blobuploader) - INFO - Done attempting. 04:44:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:44:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:44:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:44:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:44:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:44:54 INFO - (blobuploader) - INFO - Done attempting. 04:44:54 INFO - (blobuploader) - INFO - Iteration through files over. 04:44:54 INFO - Return code: 0 04:44:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:44:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:44:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eab022ae7fd7ba9c17f1f7edc403700664b743697e3ece8b8539728dee35f499a9487d9d09da715ec9c8bf28518896f754dae730bfba30dd5ade9e13ce080d48", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adebf9151689c530c4107a755d51f7019b4a4e12ce697081888e54509f57b328d0b3768da0d4d6a28808c1820a25cc8865440bf34414210027300d536d4f2e2a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/137f2a5e53891d5c89301c0cf3e347a5ca4bdec6d6a5602d48f05594d47fbe2e96b7417ebed5184fc26fbe0efe7fa1e9d8470a5e2036a7157b7cc6f0da54bc16", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6698a16edf2457655d297f1d300db366ed49ec2727134163cffdfbe2833cc5b26a331a1a7416a6fc19e910a49c55897097607f19a2e0e28951c47f26268f6b72"} 04:44:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:44:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:44:54 INFO - Contents: 04:44:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eab022ae7fd7ba9c17f1f7edc403700664b743697e3ece8b8539728dee35f499a9487d9d09da715ec9c8bf28518896f754dae730bfba30dd5ade9e13ce080d48", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adebf9151689c530c4107a755d51f7019b4a4e12ce697081888e54509f57b328d0b3768da0d4d6a28808c1820a25cc8865440bf34414210027300d536d4f2e2a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/137f2a5e53891d5c89301c0cf3e347a5ca4bdec6d6a5602d48f05594d47fbe2e96b7417ebed5184fc26fbe0efe7fa1e9d8470a5e2036a7157b7cc6f0da54bc16", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6698a16edf2457655d297f1d300db366ed49ec2727134163cffdfbe2833cc5b26a331a1a7416a6fc19e910a49c55897097607f19a2e0e28951c47f26268f6b72"} 04:44:54 INFO - Running post-run listener: copy_logs_to_upload_dir 04:44:54 INFO - Copying logs to upload dir... 04:44:54 INFO - mkdir: /builds/slave/test/build/upload/logs 04:44:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.512022 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-01 04:44:54.098188) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-01 04:44:54.102011) ========= 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.KnjH3M5z9q/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.7bzrk0XQ7d/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eab022ae7fd7ba9c17f1f7edc403700664b743697e3ece8b8539728dee35f499a9487d9d09da715ec9c8bf28518896f754dae730bfba30dd5ade9e13ce080d48", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adebf9151689c530c4107a755d51f7019b4a4e12ce697081888e54509f57b328d0b3768da0d4d6a28808c1820a25cc8865440bf34414210027300d536d4f2e2a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/137f2a5e53891d5c89301c0cf3e347a5ca4bdec6d6a5602d48f05594d47fbe2e96b7417ebed5184fc26fbe0efe7fa1e9d8470a5e2036a7157b7cc6f0da54bc16", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6698a16edf2457655d297f1d300db366ed49ec2727134163cffdfbe2833cc5b26a331a1a7416a6fc19e910a49c55897097607f19a2e0e28951c47f26268f6b72"} build_url:https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011107 build_url: 'https://queue.taskcluster.net/v1/task/E8DQqfyuSdCgpnsvy8VxxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eab022ae7fd7ba9c17f1f7edc403700664b743697e3ece8b8539728dee35f499a9487d9d09da715ec9c8bf28518896f754dae730bfba30dd5ade9e13ce080d48", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adebf9151689c530c4107a755d51f7019b4a4e12ce697081888e54509f57b328d0b3768da0d4d6a28808c1820a25cc8865440bf34414210027300d536d4f2e2a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/137f2a5e53891d5c89301c0cf3e347a5ca4bdec6d6a5602d48f05594d47fbe2e96b7417ebed5184fc26fbe0efe7fa1e9d8470a5e2036a7157b7cc6f0da54bc16", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6698a16edf2457655d297f1d300db366ed49ec2727134163cffdfbe2833cc5b26a331a1a7416a6fc19e910a49c55897097607f19a2e0e28951c47f26268f6b72"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-01 04:44:54.140053) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 04:44:54.140429) ========= 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.KnjH3M5z9q/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.7bzrk0XQ7d/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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.005144 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 04:44:54.190039) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-01 04:44:54.190468) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-01 04:44:54.190837) ========= ========= Total master_lag: 0.22 =========