builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0099 starttime: 1484151751.62 results: success (0) buildid: 20170111064105 builduid: 436d7f61a8e34b309d7a3e14525f209e revision: d9206eb08d6bb601658d8af93cbe9eacb98e18ca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 08:22:31.617279) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 08:22:31.618181) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 08:22:31.618501) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 08:22:31.650188) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 08:22:31.650619) ========= 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.1I3h6dsc0X/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.zufEuHs7Mp/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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-11 08:22:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2017-01-11 08:22:31 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.085678 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 08:22:31.760403) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 08:22:31.760732) ========= 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.1I3h6dsc0X/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.zufEuHs7Mp/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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.026904 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 08:22:31.810310) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 08:22:31.810700) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d9206eb08d6bb601658d8af93cbe9eacb98e18ca --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 d9206eb08d6bb601658d8af93cbe9eacb98e18ca --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1I3h6dsc0X/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.zufEuHs7Mp/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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-11 08:22:31,904 truncating revision to first 12 chars 2017-01-11 08:22:31,904 Setting DEBUG logging. 2017-01-11 08:22:31,905 attempt 1/10 2017-01-11 08:22:31,905 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d9206eb08d6b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-11 08:22:32,529 unpacking tar archive at: mozilla-central-d9206eb08d6b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.892105 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 08:22:32.739976) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 08:22:32.740256) ========= script_repo_revision: d9206eb08d6bb601658d8af93cbe9eacb98e18ca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 08:22:32.740635) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 08:22:32.740905) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 08:22:32.763937) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-11 08:22:32.764353) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1I3h6dsc0X/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.zufEuHs7Mp/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:22:32 INFO - MultiFileLogger online at 20170111 08:22:32 in /builds/slave/test 08:22:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:22:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:22:32 INFO - {'append_to_log': False, 08:22:32 INFO - 'base_work_dir': '/builds/slave/test', 08:22:32 INFO - 'blob_upload_branch': 'Firefox', 08:22:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:22:32 INFO - 'branch': 'Firefox', 08:22:32 INFO - 'buildbot_json_path': 'buildprops.json', 08:22:32 INFO - 'config_files': ('talos/mac_config.py',), 08:22:32 INFO - 'default_actions': ('clobber', 08:22:32 INFO - 'read-buildbot-config', 08:22:32 INFO - 'download-and-extract', 08:22:32 INFO - 'populate-webroot', 08:22:32 INFO - 'create-virtualenv', 08:22:32 INFO - 'install', 08:22:32 INFO - 'run-tests'), 08:22:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:22:32 INFO - 'download_minidump_stackwalk': True, 08:22:32 INFO - 'download_symbols': 'ondemand', 08:22:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:22:32 INFO - 'tooltool.py': '/tools/tooltool.py', 08:22:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:22:32 INFO - '/tools/misc-python/virtualenv.py')}, 08:22:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:22:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:22:32 INFO - 'installer_path': 'installer.exe', 08:22:32 INFO - 'log_level': 'info', 08:22:32 INFO - 'log_name': 'talos', 08:22:32 INFO - 'log_to_console': True, 08:22:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:22:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:22:32 INFO - 'opt_config_files': (), 08:22:32 INFO - 'pip_index': False, 08:22:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:22:32 INFO - 'cmd': ('bash', 08:22:32 INFO - '-c', 08:22:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:22:32 INFO - 'enabled': True, 08:22:32 INFO - 'halt_on_failure': False, 08:22:32 INFO - 'name': 'check_screen_resolution'},), 08:22:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:22:32 INFO - 'cmd': ('bash', 08:22:32 INFO - '-c', 08:22:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:22:32 INFO - 'enabled': True, 08:22:32 INFO - 'halt_on_failure': False, 08:22:32 INFO - 'name': 'check_screen_resolution'},), 08:22:32 INFO - 'run_cmd_checks_enabled': True, 08:22:32 INFO - 'sps_profile': False, 08:22:32 INFO - 'sps_profile_interval': 0, 08:22:32 INFO - 'suite': 'dromaeojs-e10s', 08:22:32 INFO - 'system_bits': '32', 08:22:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:22:32 INFO - 'title': 't-yosemite-r7-0099', 08:22:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:22:32 INFO - 'use_talos_json': True, 08:22:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:22:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:22:32 INFO - 'work_dir': 'build'} 08:22:32 INFO - [mozharness: 2017-01-11 16:22:32.970425Z] Running clobber step. 08:22:32 INFO - Running pre-action listener: _resource_record_pre_action 08:22:32 INFO - Running main action method: clobber 08:22:32 INFO - rmtree: /builds/slave/test/build 08:22:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:22:35 INFO - Running post-action listener: _resource_record_post_action 08:22:35 INFO - [mozharness: 2017-01-11 16:22:35.368225Z] Finished clobber step (success) 08:22:35 INFO - [mozharness: 2017-01-11 16:22:35.368357Z] Running read-buildbot-config step. 08:22:35 INFO - Running pre-action listener: _resource_record_pre_action 08:22:35 INFO - Running main action method: read_buildbot_config 08:22:35 INFO - Using buildbot properties: 08:22:35 INFO - { 08:22:35 INFO - "project": "", 08:22:35 INFO - "product": "firefox", 08:22:35 INFO - "script_repo_revision": "production", 08:22:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:22:35 INFO - "repository": "", 08:22:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:22:35 INFO - "buildid": "20170111064105", 08:22:35 INFO - "pgo_build": "False", 08:22:35 INFO - "basedir": "/builds/slave/test", 08:22:35 INFO - "buildnumber": 35, 08:22:35 INFO - "slavename": "t-yosemite-r7-0099", 08:22:35 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:22:35 INFO - "platform": "yosemite_r7", 08:22:35 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:22:35 INFO - "branch": "mozilla-central", 08:22:35 INFO - "repo_path": "mozilla-central", 08:22:35 INFO - "revision": "d9206eb08d6bb601658d8af93cbe9eacb98e18ca", 08:22:35 INFO - "stage_platform": "macosx64", 08:22:35 INFO - "builduid": "436d7f61a8e34b309d7a3e14525f209e", 08:22:35 INFO - "slavebuilddir": "test" 08:22:35 INFO - } 08:22:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:22:35 INFO - Running post-action listener: _resource_record_post_action 08:22:35 INFO - [mozharness: 2017-01-11 16:22:35.371733Z] Finished read-buildbot-config step (success) 08:22:35 INFO - [mozharness: 2017-01-11 16:22:35.371844Z] Running download-and-extract step. 08:22:35 INFO - Running pre-action listener: _resource_record_pre_action 08:22:35 INFO - Running main action method: download_and_extract 08:22:35 INFO - mkdir: /builds/slave/test/build/tests 08:22:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:35 INFO - trying https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:22:35 INFO - Downloading https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:22:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 08:22:36 INFO - Downloaded 1199 bytes. 08:22:36 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:22:36 INFO - Using the following test package requirements: 08:22:36 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:22:36 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:22:36 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:22:36 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:22:36 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:22:36 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:22:36 INFO - u'jsshell-mac.zip'], 08:22:36 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:22:36 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:22:36 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:22:36 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:22:36 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:22:36 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:22:36 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:22:36 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:22:36 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:22:36 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:22:36 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:22:36 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:22:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:22:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:22:36 INFO - Fetch https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:22:37 INFO - Expected file size: 16311438 08:22:37 INFO - Obtained file size: 16311438 08:22:44 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:22:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:22:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:22:44 INFO - Fetch https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:22:45 INFO - Expected file size: 16311438 08:22:45 INFO - Obtained file size: 16311438 08:22:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:22:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:22:51 INFO - Fetch https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:22:52 INFO - Expected file size: 14403529 08:22:52 INFO - Obtained file size: 14403529 08:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:53 INFO - trying https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:22:53 INFO - Downloading https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:22:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 08:22:55 INFO - Downloaded 69838582 bytes. 08:22:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:22:55 INFO - mkdir: /builds/slave/test/properties 08:22:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:22:55 INFO - Writing to file /builds/slave/test/properties/build_url 08:22:55 INFO - Contents: 08:22:55 INFO - build_url:https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:22:55 INFO - Running post-action listener: _resource_record_post_action 08:22:55 INFO - Running post-action listener: set_extra_try_arguments 08:22:55 INFO - [mozharness: 2017-01-11 16:22:55.691987Z] Finished download-and-extract step (success) 08:22:55 INFO - [mozharness: 2017-01-11 16:22:55.692109Z] Running populate-webroot step. 08:22:55 INFO - Running pre-action listener: _resource_record_pre_action 08:22:55 INFO - Running main action method: populate_webroot 08:22:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:22:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:22:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:22:55 INFO - '--tppagecycles', 08:22:55 INFO - '7'], 08:22:55 INFO - 'tests': ['tsvgm']}}, 08:22:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:22:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:22:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:22:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:22:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:22:55 INFO - 'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:22:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:22:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:22:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:22:55 INFO - 'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['damp', 'tps']}, 08:22:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:22:55 INFO - 'tests': ['damp', 'tps']}, 08:22:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['dromaeo_dom']}, 08:22:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:22:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['basic_compositor_video']}, 08:22:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:22:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['a11yr', 08:22:55 INFO - 'ts_paint', 08:22:55 INFO - 'tpaint', 08:22:55 INFO - 'sessionrestore', 08:22:55 INFO - 'sessionrestore_no_auto_restore', 08:22:55 INFO - 'tabpaint']}, 08:22:55 INFO - 'other-e10s': {'tests': ['a11yr', 08:22:55 INFO - 'ts_paint', 08:22:55 INFO - 'tpaint', 08:22:55 INFO - 'sessionrestore', 08:22:55 INFO - 'sessionrestore_no_auto_restore', 08:22:55 INFO - 'tabpaint']}, 08:22:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:22:55 INFO - 'ts_paint', 08:22:55 INFO - 'tpaint', 08:22:55 INFO - 'sessionrestore', 08:22:55 INFO - 'sessionrestore_no_auto_restore', 08:22:55 INFO - 'tabpaint']}, 08:22:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:22:55 INFO - 'ts_paint', 08:22:55 INFO - 'tpaint', 08:22:55 INFO - 'sessionrestore', 08:22:55 INFO - 'sessionrestore_no_auto_restore', 08:22:55 INFO - 'tabpaint']}, 08:22:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['a11yr', 08:22:55 INFO - 'ts_paint', 08:22:55 INFO - 'tpaint', 08:22:55 INFO - 'sessionrestore', 08:22:55 INFO - 'sessionrestore_no_auto_restore', 08:22:55 INFO - 'tabpaint']}, 08:22:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['a11yr', 08:22:55 INFO - 'ts_paint', 08:22:55 INFO - 'tpaint', 08:22:55 INFO - 'sessionrestore', 08:22:55 INFO - 'sessionrestore_no_auto_restore', 08:22:55 INFO - 'tabpaint']}, 08:22:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['tsvgx', 08:22:55 INFO - 'tsvgr_opacity', 08:22:55 INFO - 'tart', 08:22:55 INFO - 'tscrollx', 08:22:55 INFO - 'cart', 08:22:55 INFO - 'tsvg_static']}, 08:22:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:22:55 INFO - 'tsvgr_opacity', 08:22:55 INFO - 'tart', 08:22:55 INFO - 'tscrollx', 08:22:55 INFO - 'cart', 08:22:55 INFO - 'tsvg_static']}, 08:22:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:22:55 INFO - 'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['tp5o']}, 08:22:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:22:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:22:55 INFO - 'talos_options': ['--disable-e10s', 08:22:55 INFO - '--xperf_path', 08:22:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:22:55 INFO - 'tests': ['tp5n']}, 08:22:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:22:55 INFO - 'talos_options': ['--xperf_path', 08:22:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:22:55 INFO - 'tests': ['tp5n']}}, 08:22:55 INFO - 'talos.zip': {'path': '', 08:22:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:22:55 INFO - Running post-action listener: _resource_record_post_action 08:22:55 INFO - [mozharness: 2017-01-11 16:22:55.710737Z] Finished populate-webroot step (success) 08:22:55 INFO - [mozharness: 2017-01-11 16:22:55.710846Z] Running create-virtualenv step. 08:22:55 INFO - Running pre-action listener: _resource_record_pre_action 08:22:55 INFO - Running main action method: create_virtualenv 08:22:55 INFO - Creating virtualenv /builds/slave/test/build/venv 08:22:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:22:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:22:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:22:55 INFO - Using real prefix '/tools/python27' 08:22:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:22:56 INFO - Installing distribute.............................................................................................................................................................................................done. 08:22:59 INFO - Installing pip.................done. 08:22:59 INFO - Return code: 0 08:22:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:22:59 INFO - Reading from file tmpfile_stdout 08:22:59 INFO - Output received: 08:22:59 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:22:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:59 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 0x10ccbeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9122571c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9cd30>, '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 0x10c9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca91be8>, '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.1I3h6dsc0X/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.zufEuHs7Mp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:22:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:22:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1I3h6dsc0X/Render', 08:22:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:59 INFO - 'HOME': '/Users/cltbld', 08:22:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:59 INFO - 'LOGNAME': 'cltbld', 08:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:59 INFO - 'MOZ_NO_REMOTE': '1', 08:22:59 INFO - 'NO_EM_RESTART': '1', 08:22:59 INFO - 'PAGER': '/bin/cat', 08:22:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:59 INFO - 'PWD': '/builds/slave/test', 08:22:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:59 INFO - 'SHELL': '/bin/bash', 08:22:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zufEuHs7Mp/Listeners', 08:22:59 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 08:22:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:59 INFO - 'USER': 'cltbld', 08:22:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:59 INFO - 'XPC_FLAGS': '0x0', 08:22:59 INFO - 'XPC_SERVICE_NAME': '0', 08:22:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:22:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:22:59 INFO - Cleaning up... 08:22:59 INFO - Return code: 0 08:22:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:59 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 0x10ccbeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9122571c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9cd30>, '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 0x10c9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca91be8>, '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.1I3h6dsc0X/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.zufEuHs7Mp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:22:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:22:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1I3h6dsc0X/Render', 08:22:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:59 INFO - 'HOME': '/Users/cltbld', 08:22:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:59 INFO - 'LOGNAME': 'cltbld', 08:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:59 INFO - 'MOZ_NO_REMOTE': '1', 08:22:59 INFO - 'NO_EM_RESTART': '1', 08:22:59 INFO - 'PAGER': '/bin/cat', 08:22:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:59 INFO - 'PWD': '/builds/slave/test', 08:22:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:59 INFO - 'SHELL': '/bin/bash', 08:22:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zufEuHs7Mp/Listeners', 08:22:59 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 08:22:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:59 INFO - 'USER': 'cltbld', 08:22:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:59 INFO - 'XPC_FLAGS': '0x0', 08:22:59 INFO - 'XPC_SERVICE_NAME': '0', 08:22:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:00 INFO - Downloading/unpacking psutil>=3.1.1 08:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:23:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:23:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:23:03 INFO - Installing collected packages: psutil 08:23:03 INFO - Running setup.py install for psutil 08:23:03 INFO - building 'psutil._psutil_osx' extension 08:23:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:23:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:23:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:23:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:23:04 INFO - building 'psutil._psutil_posix' extension 08:23:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:23:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:23:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:23:04 INFO - ^ 08:23:04 INFO - 1 warning generated. 08:23:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:23:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:23:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:23:04 INFO - Successfully installed psutil 08:23:04 INFO - Cleaning up... 08:23:04 INFO - Return code: 0 08:23:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:23:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:05 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 0x10ccbeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9122571c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9cd30>, '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 0x10c9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca91be8>, '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.1I3h6dsc0X/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.zufEuHs7Mp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:23:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:23:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1I3h6dsc0X/Render', 08:23:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:05 INFO - 'HOME': '/Users/cltbld', 08:23:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:05 INFO - 'LOGNAME': 'cltbld', 08:23:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:05 INFO - 'MOZ_NO_REMOTE': '1', 08:23:05 INFO - 'NO_EM_RESTART': '1', 08:23:05 INFO - 'PAGER': '/bin/cat', 08:23:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:05 INFO - 'PWD': '/builds/slave/test', 08:23:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:05 INFO - 'SHELL': '/bin/bash', 08:23:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zufEuHs7Mp/Listeners', 08:23:05 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 08:23:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:05 INFO - 'USER': 'cltbld', 08:23:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:05 INFO - 'XPC_FLAGS': '0x0', 08:23:05 INFO - 'XPC_SERVICE_NAME': '0', 08:23:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:23:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:23:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:23:08 INFO - Installing collected packages: mozsystemmonitor 08:23:08 INFO - Running setup.py install for mozsystemmonitor 08:23:08 INFO - Successfully installed mozsystemmonitor 08:23:08 INFO - Cleaning up... 08:23:08 INFO - Return code: 0 08:23:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:08 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 0x10ccbeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9122571c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9cd30>, '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 0x10c9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca91be8>, '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.1I3h6dsc0X/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.zufEuHs7Mp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:23:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1I3h6dsc0X/Render', 08:23:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:08 INFO - 'HOME': '/Users/cltbld', 08:23:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:08 INFO - 'LOGNAME': 'cltbld', 08:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:08 INFO - 'MOZ_NO_REMOTE': '1', 08:23:08 INFO - 'NO_EM_RESTART': '1', 08:23:08 INFO - 'PAGER': '/bin/cat', 08:23:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:08 INFO - 'PWD': '/builds/slave/test', 08:23:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:08 INFO - 'SHELL': '/bin/bash', 08:23:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zufEuHs7Mp/Listeners', 08:23:08 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 08:23:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:08 INFO - 'USER': 'cltbld', 08:23:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:08 INFO - 'XPC_FLAGS': '0x0', 08:23:08 INFO - 'XPC_SERVICE_NAME': '0', 08:23:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:08 INFO - Downloading/unpacking jsonschema==2.5.1 08:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:23:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:11 INFO - Downloading functools32-3.2.3-2.tar.gz 08:23:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:23:11 INFO - warning: no files found matching '*.txt' 08:23:11 INFO - no previously-included directories found matching 'build' 08:23:11 INFO - no previously-included directories found matching 'dist' 08:23:11 INFO - no previously-included directories found matching '.git*' 08:23:11 INFO - Installing collected packages: jsonschema, functools32 08:23:11 INFO - Running setup.py install for functools32 08:23:12 INFO - warning: no files found matching '*.txt' 08:23:12 INFO - no previously-included directories found matching 'build' 08:23:12 INFO - no previously-included directories found matching 'dist' 08:23:12 INFO - no previously-included directories found matching '.git*' 08:23:12 INFO - Successfully installed jsonschema functools32 08:23:12 INFO - Cleaning up... 08:23:12 INFO - Return code: 0 08:23:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:23:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:12 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 0x10ccbeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9122571c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9cd30>, '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 0x10c9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca91be8>, '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.1I3h6dsc0X/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.zufEuHs7Mp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:23:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:23:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1I3h6dsc0X/Render', 08:23:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:12 INFO - 'HOME': '/Users/cltbld', 08:23:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:12 INFO - 'LOGNAME': 'cltbld', 08:23:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:12 INFO - 'MOZ_NO_REMOTE': '1', 08:23:12 INFO - 'NO_EM_RESTART': '1', 08:23:12 INFO - 'PAGER': '/bin/cat', 08:23:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:12 INFO - 'PWD': '/builds/slave/test', 08:23:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:12 INFO - 'SHELL': '/bin/bash', 08:23:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zufEuHs7Mp/Listeners', 08:23:12 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 08:23:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:12 INFO - 'USER': 'cltbld', 08:23:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:12 INFO - 'XPC_FLAGS': '0x0', 08:23:12 INFO - 'XPC_SERVICE_NAME': '0', 08:23:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:23:12 INFO - Cleaning up... 08:23:12 INFO - Return code: 0 08:23:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:23:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:12 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 0x10ccbeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9122571c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9cd30>, '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 0x10c9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca91be8>, '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.1I3h6dsc0X/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.zufEuHs7Mp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:23:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:23:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1I3h6dsc0X/Render', 08:23:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:12 INFO - 'HOME': '/Users/cltbld', 08:23:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:12 INFO - 'LOGNAME': 'cltbld', 08:23:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:12 INFO - 'MOZ_NO_REMOTE': '1', 08:23:12 INFO - 'NO_EM_RESTART': '1', 08:23:12 INFO - 'PAGER': '/bin/cat', 08:23:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:12 INFO - 'PWD': '/builds/slave/test', 08:23:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:12 INFO - 'SHELL': '/bin/bash', 08:23:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zufEuHs7Mp/Listeners', 08:23:12 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 08:23:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:12 INFO - 'USER': 'cltbld', 08:23:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:12 INFO - 'XPC_FLAGS': '0x0', 08:23:12 INFO - 'XPC_SERVICE_NAME': '0', 08:23:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:12 INFO - Downloading/unpacking blobuploader==1.2.4 08:23:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:15 INFO - Downloading blobuploader-1.2.4.tar.gz 08:23:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:23:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:23:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:16 INFO - Downloading docopt-0.6.1.tar.gz 08:23:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:23:16 INFO - Installing collected packages: blobuploader, requests, docopt 08:23:16 INFO - Running setup.py install for blobuploader 08:23:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:23:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:23:16 INFO - Running setup.py install for requests 08:23:17 INFO - Running setup.py install for docopt 08:23:17 INFO - Successfully installed blobuploader requests docopt 08:23:17 INFO - Cleaning up... 08:23:17 INFO - Return code: 0 08:23:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:17 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 0x10ccbeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9122571c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9cd30>, '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 0x10c9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca91be8>, '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.1I3h6dsc0X/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.zufEuHs7Mp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:23:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:23:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1I3h6dsc0X/Render', 08:23:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:17 INFO - 'HOME': '/Users/cltbld', 08:23:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:17 INFO - 'LOGNAME': 'cltbld', 08:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:17 INFO - 'MOZ_NO_REMOTE': '1', 08:23:17 INFO - 'NO_EM_RESTART': '1', 08:23:17 INFO - 'PAGER': '/bin/cat', 08:23:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:17 INFO - 'PWD': '/builds/slave/test', 08:23:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:17 INFO - 'SHELL': '/bin/bash', 08:23:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zufEuHs7Mp/Listeners', 08:23:17 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 08:23:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:17 INFO - 'USER': 'cltbld', 08:23:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:17 INFO - 'XPC_FLAGS': '0x0', 08:23:17 INFO - 'XPC_SERVICE_NAME': '0', 08:23:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:23:17 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-KZ485s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:23:17 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-jo1E0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:23:18 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-npHiHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:23:18 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-2zl2dt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:23:18 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-fLsQ0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:23:18 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-ocwZol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:23:18 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-hsXrhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:23:18 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-h2JdRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:23:18 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-iI8Zmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:23:18 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-XhQuQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:23:18 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-RDFIiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:23:18 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-ygqBRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:23:19 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-omZbKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:23:19 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-aR5PHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:23:19 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-mzCqXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:23:19 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-ZtUwkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:23:19 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-8Da3Ac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:23:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:23:19 INFO - Running setup.py install for manifestparser 08:23:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:23:19 INFO - Running setup.py install for mozcrash 08:23:19 INFO - Running setup.py install for mozdebug 08:23:20 INFO - Running setup.py install for mozdevice 08:23:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:23:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:23:20 INFO - Running setup.py install for mozfile 08:23:20 INFO - Running setup.py install for mozhttpd 08:23:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:23:20 INFO - Running setup.py install for mozinfo 08:23:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:23:20 INFO - Running setup.py install for mozInstall 08:23:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:23:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:23:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:23:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:23:20 INFO - Running setup.py install for mozleak 08:23:20 INFO - Running setup.py install for mozlog 08:23:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:23:21 INFO - Running setup.py install for moznetwork 08:23:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:23:21 INFO - Running setup.py install for mozprocess 08:23:21 INFO - Running setup.py install for mozprofile 08:23:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:23:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:23:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:23:21 INFO - Running setup.py install for mozrunner 08:23:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:23:21 INFO - Running setup.py install for mozscreenshot 08:23:21 INFO - Running setup.py install for moztest 08:23:22 INFO - Running setup.py install for mozversion 08:23:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:23:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:23:22 INFO - Cleaning up... 08:23:22 INFO - Return code: 0 08:23:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:23:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:22 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 0x10ccbeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9122571c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9cd30>, '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 0x10c9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca91be8>, '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.1I3h6dsc0X/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.zufEuHs7Mp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:23:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:23:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1I3h6dsc0X/Render', 08:23:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:22 INFO - 'HOME': '/Users/cltbld', 08:23:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:22 INFO - 'LOGNAME': 'cltbld', 08:23:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:22 INFO - 'MOZ_NO_REMOTE': '1', 08:23:22 INFO - 'NO_EM_RESTART': '1', 08:23:22 INFO - 'PAGER': '/bin/cat', 08:23:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:22 INFO - 'PWD': '/builds/slave/test', 08:23:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:22 INFO - 'SHELL': '/bin/bash', 08:23:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zufEuHs7Mp/Listeners', 08:23:22 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 08:23:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:22 INFO - 'USER': 'cltbld', 08:23:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:22 INFO - 'XPC_FLAGS': '0x0', 08:23:22 INFO - 'XPC_SERVICE_NAME': '0', 08:23:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:23:23 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-Go1jds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:23:23 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-zpN8_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:23:23 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-xAVwo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:23:23 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-4hkfHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:23:23 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-D6RI9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:23:23 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-8eRCVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:23:23 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-gnNSCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:23:24 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-KlOBgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:23:24 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-C1syzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:23:24 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-_AkxWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:23:24 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-3jAru8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:23:24 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-zuNZJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:23:24 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-aYW4mJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:23:24 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-Nf5LJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:23:24 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-nZyijK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:23:24 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-oVHjAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:23:24 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)) 08:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:23:24 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-zRH6Jv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:23:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:28 INFO - Downloading blessings-1.6.tar.gz 08:23:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:23:28 INFO - Installing collected packages: blessings 08:23:28 INFO - Running setup.py install for blessings 08:23:28 INFO - Successfully installed blessings 08:23:28 INFO - Cleaning up... 08:23:28 INFO - Return code: 0 08:23:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:23:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:23:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:23:28 INFO - Reading from file tmpfile_stdout 08:23:28 INFO - Current package versions: 08:23:28 INFO - blessings == 1.6 08:23:28 INFO - blobuploader == 1.2.4 08:23:28 INFO - docopt == 0.6.1 08:23:28 INFO - functools32 == 3.2.3-2 08:23:28 INFO - jsonschema == 2.5.1 08:23:28 INFO - manifestparser == 1.1 08:23:28 INFO - mozInstall == 1.12 08:23:28 INFO - mozcrash == 1.0 08:23:28 INFO - mozdebug == 0.1 08:23:28 INFO - mozdevice == 0.49 08:23:28 INFO - mozfile == 1.2 08:23:28 INFO - mozhttpd == 0.7 08:23:28 INFO - mozinfo == 0.9 08:23:28 INFO - mozleak == 0.1 08:23:28 INFO - mozlog == 3.4 08:23:28 INFO - moznetwork == 0.27 08:23:28 INFO - mozprocess == 0.24 08:23:28 INFO - mozprofile == 0.28 08:23:28 INFO - mozrunner == 6.13 08:23:28 INFO - mozscreenshot == 0.1 08:23:28 INFO - mozsystemmonitor == 0.3 08:23:28 INFO - moztest == 0.8 08:23:28 INFO - mozversion == 1.4 08:23:28 INFO - psutil == 3.1.1 08:23:28 INFO - requests == 1.2.3 08:23:28 INFO - wsgiref == 0.1.2 08:23:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:23:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ccbeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9122571c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9cd30>, '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 0x10c9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca91be8>, '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.1I3h6dsc0X/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.zufEuHs7Mp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:23:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:23:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1I3h6dsc0X/Render', 08:23:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:28 INFO - 'HOME': '/Users/cltbld', 08:23:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:28 INFO - 'LOGNAME': 'cltbld', 08:23:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:28 INFO - 'MOZ_NO_REMOTE': '1', 08:23:28 INFO - 'NO_EM_RESTART': '1', 08:23:28 INFO - 'PAGER': '/bin/cat', 08:23:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:28 INFO - 'PWD': '/builds/slave/test', 08:23:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:28 INFO - 'SHELL': '/bin/bash', 08:23:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zufEuHs7Mp/Listeners', 08:23:28 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 08:23:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:28 INFO - 'USER': 'cltbld', 08:23:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:28 INFO - 'XPC_FLAGS': '0x0', 08:23:28 INFO - 'XPC_SERVICE_NAME': '0', 08:23:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:23:28 INFO - Cleaning up... 08:23:28 INFO - Return code: 0 08:23:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:23:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ccbeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9122571c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9cd30>, '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 0x10c9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca91be8>, '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.1I3h6dsc0X/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.zufEuHs7Mp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:23:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:23:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1I3h6dsc0X/Render', 08:23:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:28 INFO - 'HOME': '/Users/cltbld', 08:23:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:28 INFO - 'LOGNAME': 'cltbld', 08:23:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:28 INFO - 'MOZ_NO_REMOTE': '1', 08:23:28 INFO - 'NO_EM_RESTART': '1', 08:23:28 INFO - 'PAGER': '/bin/cat', 08:23:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:28 INFO - 'PWD': '/builds/slave/test', 08:23:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:28 INFO - 'SHELL': '/bin/bash', 08:23:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zufEuHs7Mp/Listeners', 08:23:28 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 08:23:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:28 INFO - 'USER': 'cltbld', 08:23:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:28 INFO - 'XPC_FLAGS': '0x0', 08:23:28 INFO - 'XPC_SERVICE_NAME': '0', 08:23:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:23:29 INFO - Cleaning up... 08:23:29 INFO - Return code: 0 08:23:29 INFO - Running post-action listener: _resource_record_post_action 08:23:29 INFO - Running post-action listener: _start_resource_monitoring 08:23:29 INFO - Starting resource monitoring. 08:23:29 INFO - [mozharness: 2017-01-11 16:23:29.280861Z] Finished create-virtualenv step (success) 08:23:29 INFO - [mozharness: 2017-01-11 16:23:29.281889Z] Running install step. 08:23:29 INFO - Running pre-action listener: _resource_record_pre_action 08:23:29 INFO - Running main action method: install 08:23:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:23:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:23:29 INFO - Reading from file tmpfile_stdout 08:23:29 INFO - Detecting whether we're running mozinstall >=1.0... 08:23:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:23:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:23:29 INFO - Reading from file tmpfile_stdout 08:23:29 INFO - Output received: 08:23:29 INFO - Usage: mozinstall [options] installer 08:23:29 INFO - Options: 08:23:29 INFO - -h, --help show this help message and exit 08:23:29 INFO - -d DEST, --destination=DEST 08:23:29 INFO - Directory to install application into. [default: 08:23:29 INFO - "/builds/slave/test"] 08:23:29 INFO - --app=APP Application being installed. [default: firefox] 08:23:29 INFO - mkdir: /builds/slave/test/build/application 08:23:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:23:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:23:51 INFO - Reading from file tmpfile_stdout 08:23:51 INFO - Output received: 08:23:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:23:51 INFO - Running post-action listener: _resource_record_post_action 08:23:51 INFO - [mozharness: 2017-01-11 16:23:51.389444Z] Finished install step (success) 08:23:51 INFO - [mozharness: 2017-01-11 16:23:51.389592Z] Running run-tests step. 08:23:51 INFO - Running pre-action listener: _resource_record_pre_action 08:23:51 INFO - Running main action method: run_tests 08:23:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:23:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:23:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:23:51 INFO - 2017-01-11 08:23:51.404 screenresolution[1627:7670] starting screenresolution argv=screenresolution get 08:23:51 INFO - 2017-01-11 08:23:51.407 screenresolution[1627:7670] Display 0: 1600x1200x32@60 08:23:51 INFO - 2017-01-11 08:23:51.413 screenresolution[1628:7672] starting screenresolution argv=screenresolution list 08:23:51 INFO - Available Modes on Display 0 08:23:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:23:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:23:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:23:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:23:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:23:51 INFO - Intel Iris: 08:23:51 INFO - Chipset Model: Intel Iris 08:23:51 INFO - Type: GPU 08:23:51 INFO - Bus: Built-In 08:23:51 INFO - VRAM (Dynamic, Max): 1536 MB 08:23:51 INFO - Vendor: Intel (0x8086) 08:23:51 INFO - Device ID: 0x0a2e 08:23:51 INFO - Revision ID: 0x0009 08:23:51 INFO - Displays: 08:23:51 INFO - Display: 08:23:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:23:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:23:51 INFO - Display Serial Number: 1600x1200 60 08:23:51 INFO - Main Display: Yes 08:23:51 INFO - Mirror: Off 08:23:51 INFO - Online: Yes 08:23:51 INFO - Rotation: Supported 08:23:51 INFO - Return code: 0 08:23:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:23:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:23:51 INFO - Python 2.7.3 08:23:51 INFO - Return code: 0 08:23:51 INFO - grabbing minidump binary from tooltool 08:23:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:51 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 0x10ca9cd30>, '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 0x10c9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca91be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:23:51 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:23:51 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:23:51 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:23:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:23:51 INFO - Return code: 0 08:23:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:23:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:23:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:23:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:23:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:23:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0099', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:23:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0099 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:23:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1I3h6dsc0X/Render', 08:23:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:51 INFO - 'HOME': '/Users/cltbld', 08:23:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:51 INFO - 'LOGNAME': 'cltbld', 08:23:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:23:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:23:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:51 INFO - 'MOZ_NO_REMOTE': '1', 08:23:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:23:51 INFO - 'NO_EM_RESTART': '1', 08:23:51 INFO - 'PAGER': '/bin/cat', 08:23:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:51 INFO - 'PWD': '/builds/slave/test', 08:23:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:23:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:51 INFO - 'SHELL': '/bin/bash', 08:23:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zufEuHs7Mp/Listeners', 08:23:51 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 08:23:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:51 INFO - 'USER': 'cltbld', 08:23:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:51 INFO - 'XPC_FLAGS': '0x0', 08:23:51 INFO - 'XPC_SERVICE_NAME': '0', 08:23:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0099', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:23:51 INFO - mozversion application_buildid: 20170111064105 08:23:51 INFO - mozversion application_changeset: d9206eb08d6bb601658d8af93cbe9eacb98e18ca 08:23:51 INFO - mozversion application_display_name: Nightly 08:23:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:23:51 INFO - mozversion application_name: Firefox 08:23:51 INFO - mozversion application_remotingname: firefox 08:23:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:23:51 INFO - mozversion application_vendor: Mozilla 08:23:51 INFO - mozversion application_version: 53.0a1 08:23:51 INFO - mozversion platform_buildid: 20170111064105 08:23:51 INFO - mozversion platform_changeset: d9206eb08d6bb601658d8af93cbe9eacb98e18ca 08:23:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:23:51 INFO - mozversion platform_version: 53.0a1 08:23:51 INFO - using testdate: 1484151831 08:23:51 INFO - actual date: 1484151831 08:23:51 INFO - starting webserver on 'localhost:49240' 08:23:51 INFO - SUITE-START | Running 2 tests 08:23:51 INFO - TEST-START | dromaeo_css 08:23:51 INFO - operating with platform_type : mac_ 08:23:51 INFO - Initialising browser for dromaeo_css test... 08:23:52 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/tmpTirWE7/profile http://localhost:49240/getInfo.html) 08:23:54 INFO - PROCESS | 1638 | 2017-01-11 08:23:54.668 plugin-container[1640:7822] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e37, name = 'com.apple.tsm.portname' 08:23:54 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:54 INFO - PROCESS | 1638 | 2017-01-11 08:23:54.670 plugin-container[1640:7822] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 08:23:54 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:54 INFO - PROCESS | 1638 | 2017-01-11 08:23:54.671 plugin-container[1640:7822] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:23:54 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 08:23:54 INFO - PROCESS | 1638 | colorDepth:24 08:23:54 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 08:23:54 INFO - PROCESS | 1638 | __metrics 08:23:54 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 08:23:55 INFO - TEST-INFO | 1638: exit 0 08:23:55 INFO - Browser initialized. 08:23:55 INFO - Running cycle 1/1 for dromaeo_css test... 08:23:55 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/tmpTirWE7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:23:57 INFO - PROCESS | 1641 | 2017-01-11 08:23:57.903 plugin-container[1642:7945] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 08:23:57 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:57 INFO - PROCESS | 1641 | 2017-01-11 08:23:57.904 plugin-container[1642:7945] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 08:23:57 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:57 INFO - PROCESS | 1641 | 2017-01-11 08:23:57.904 plugin-container[1642:7945] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:24:07 INFO - PROCESS | 1641 | RSS: Main: 232722432 08:24:07 INFO - PROCESS | 1641 | 08:27:09 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 08:27:12 INFO - PROCESS | 1641 | RSS: Main: 249741312 08:27:12 INFO - PROCESS | 1641 | 08:28:55 INFO - PROCESS | 1641 | ************************* 08:28:55 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 08:28:55 INFO - PROCESS | 1641 | 08:28:55 INFO - PROCESS | 1641 | Full message: ReferenceError: fetch is not defined 08:28:55 INFO - PROCESS | 1641 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 08:28:55 INFO - PROCESS | 1641 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 08:28:55 INFO - PROCESS | 1641 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 08:28:55 INFO - PROCESS | 1641 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:28:55 INFO - PROCESS | 1641 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:28:55 INFO - PROCESS | 1641 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:28:55 INFO - PROCESS | 1641 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 08:28:55 INFO - PROCESS | 1641 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:28:55 INFO - PROCESS | 1641 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:28:55 INFO - PROCESS | 1641 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:28:55 INFO - PROCESS | 1641 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 08:28:55 INFO - PROCESS | 1641 | 08:28:55 INFO - PROCESS | 1641 | ************************* 08:30:14 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 08:30:15 INFO - PROCESS | 1641 | RSS: Main: 261083136 08:30:15 INFO - PROCESS | 1641 | 08:33:17 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 08:33:18 INFO - PROCESS | 1641 | RSS: Main: 268750848 08:33:18 INFO - PROCESS | 1641 | 08:36:20 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 08:36:20 INFO - PROCESS | 1641 | RSS: Main: 264966144 08:36:20 INFO - PROCESS | 1641 | 08:39:23 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 08:39:24 INFO - PROCESS | 1641 | RSS: Main: 270782464 08:39:24 INFO - PROCESS | 1641 | 08:42:26 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 08:42:26 INFO - PROCESS | 1641 | RSS: Main: 272871424 08:42:26 INFO - PROCESS | 1641 | 08:42:26 INFO - PROCESS | 1641 | __start_tp_report 08:42:26 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 08:42:26 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 08:42:26 INFO - PROCESS | 1641 | |i|pagename|runs| 08:42:26 INFO - PROCESS | 1641 | |0;dojo.html;3313.5526703894952;3324.5179448979916;3334.733221342293;3367.393869103562;3372.999999999999;4571.291449825274;5571.164325351199;5614.438556144389;5620.241267428901;5658.462446067627;5632.633878797876;5657.603967722261;5714.971425142869;5770.567207459437;5779.595428320019;4463.241248987663;5118.078745825741;5173.508516690908;5271.42014378418;5274.709890955995;5210.609204309692;5215.921761173586;5309.814156504523;5330.067238233313;5348.197770334462;5149.407818100929;5266.262723218752;5268.078086334876;5281.996420680059;5342.91985620216;5156.149235376159;5201.115810312256;5207.531322180984;5264.263003179558;5292.153255479144;5178.792848286064;5207;5219.530242278175;5228.79084836606;5282.154855223184;5588.804391846306;5630.859228519279;5671.461211184972;5756.165356023395;5763;6139.600925939844;6146.200993870768;6181.536384771115;6206.906896396557;6263.6241825490615;6300.810975670737;6302.149209856657;6322.051692246154;6327.082573026932;6342.429181373653;5818.505427038707;5834;5857.004309267435;5872.412758724179;5877.794277200277;6150.846228844314;6271.184745982993;6274.1843560336865;6303.716332765037;6325.430711236011;6133.233345831771;6157.199564056644;6176.598521096114;6222.81331560054;6229.750809967551;5678.233438485834;6259.530535209878;6278.466330361879;6292.4966002719675;6316.210473690789;4532.388127602731;4786.042791441726;6094.542909281821;6231.594946328474;6275.96862015687;5130.974345128325;6211.565190436626;6280.623162610258;6297.2128483939505;6316.684165791692;5031.547160755549;5032.270320803463;5041.369828771403;5047.217681259411;5079.898402032012;4402.933955990663;4433.334999749999;4444;4453.309736990772;4468.642508599304;5711.914321285184;5718.428157184248;5749.597528172988;5768.625039372428;5840.1239814027385;6241.937580624226;6311.147995020705;6317.1156038154695;6320.051992201206;6329.841753956188;5518.310211223597;5630;5771.740271687784;5822.068469045017;5849.64902105875;5358.517733404013;5387.595930305243;5414.675119492843;5443.673379597237;5468.316460442445;6257.155284036688;6266.530010249249;6289.339619340087;6382.489400848014;6395.584287021329;6296.874062518632;6301.747930082929;6306.65313407767;6308.250172315762;6314.55798094129;5583.2462617547735;6216.7824126155365;6271.749130034747;6418.1656384669695;6421.518386121153;6124.350421260821;6142.590862071414;6148.647297594522;6166.722497487534;6265.968670156803;6249.6562689052535;6304.18045654062;6305.653189074645;6305.96847015762;6327.841803954938;6073.514118870391;6184.350643182401;6186.969065154645;6190.350013248544;6205.937940620536;5946.43508866657;6015.87968240624;6018.067199584071;6020.518358531394;6078.969605151946;5246.029484545371;5674.2623458950075;5873.2658417697785;5947.1079338099635;6032.034874420072;5958.433948774859;6014.969925150346;6031.09533569968;6051.062085376773;6138.324784273637;5894.469497745224;5911.615744976562;5959.880802384015;5961.731722072431;6038.547108966757 08:42:26 INFO - PROCESS | 1641 | |1;ext.html;475.7549861821163;481.6580228038093;501.8532652888151;531.9255304257402;537.5618870620447;32339.838300808493;40826;40942.59057409425;41070.38394424086;41184;41602.751917442525;42128;42169.156616867724;42267.731968040935;43158.56841431585;8428.32573394127;8628.396012279143;9311.627534898596;9509.80980380392;11369.2609980351;9482.857757133648;11534.538618455252;11541.249818761795;11553.075753939665;11589.304641721481;11509.42452877357;11563.942180289087;11579.768404631903;11597.594084207054;11630.883691163064;11466.827997580043;11530.654080377519;11531.942340288246;11540.884591154065;11546.364949927793;11399.772004559862;11460.312381257152;11465.484053217626;11491.885081149165;11494.482748276349;40838.59161408407;41066.589334106575;41317.58682413198;41345.58654413478;41486.1702765943;3628.6189950055127;3958.8812335629977;4086.570910054461;4127.318992366256;4235.2800023996;25317.62023569648;28141.43717125646;28459;30388.69611303903;31322.903698370676;3149.6062992125985;3185.044486654018;3233.514972754082;3252.08941496379;3303.0586282909494;387743;415649;456780;457527.71236143936;458648;61553;61925.07112393318;62144;62173.689131554056;62232.377676223565;64405;64661.67669161624;65120;65153;65315;15629.06225626466;15860.365585376456;16083;16085;16104.19479026044;11118.277311974696;11345.319280843176;12103.273803571814;12388.070894682935;12423;15820.92089539545;16226.675466490604;17316.134193290283;17464.5633859152;17506.824931750773;6437.742490300429;6839.1451068616425;6859.2454829968665;6892.207396149507;6899.448044156456;876.8114855306139;884.9867251991225;894.4454438248328;928.4197376639601;928.6192661008954;28918.710812892023;30013.949511766983;30096.806387225548;30293.242668933013;30353;866.2982983783156;1048.905598496139;1068.9198310126772;1076.8546246256813;1103.2497901426946;15060.54818355451;15083.47207847742;15560.533184004498;15587.766183507256;15944.601384965468;739805;761498;763671;764216;798077;52652.210216846775;53259;53343.73328133334;53452;53489;1402.0466083063616;1412.7598308287616;1441.315375196794;1447.8407375188722;1452.5279284232668;27389.452210956068;28154.2961425966;28811.71188288174;28905;29431.705682942895;46243;46291.768541158424;46673.36643217471;46690.83272918204;46860.7656961713;517585.8844940409;518945;519656;522341;522557;24575.385615359042;24589.50820983606;24655.753442465346;24900.501989960463;24956.37609059788;32543;42572;43492.56507434885;43543.782281088395;43638.34542481929;28620.14139575816;35160.64839351676;35577.644223557436;36042.27915441729;36787.816060919526;30505.54241686421;32621.205833679374;35686.28627427489;36502.634973649925;36755.44866827053;34817.30365392729;35228.94313170609;35357.292854143285;35588.82205589059;35613.287734245685;30208;31535.684643153276;31581.684183157875;31686.366272674877;31702.682973169973;20329.79670203338;22752;22835.88582057079;24279.75720242775;24556 08:42:26 INFO - PROCESS | 1641 | |2;jquery.html;377.57522786864774;383.9731218814682;388.61708824681153;396.2174704957709;414.81776935733166;12623.62129136125;15236.695266094672;19364.225430982762;20606.587868242626;20636.89681551592;19597.51006224845;19771.604567908635;20180.192792288326;21303.25438609649;21557.46106347342;5596.916046259309;5616;5764.855878603047;5832.475077243069;5851.707414629252;5677.659340439566;5788.507976821975;5789.565782566325;5798.3331916829475;5803.680797556132;4046.8785936421955;4977.576905962997;6337.429631333179;6400.03999400089;6444.548881578291;5020.723860187688;5468.179773034057;6182.845428864314;6394.744210231585;6401.103845461639;5514.834554963358;5555.833325000256;5595.132754423071;5623.212750214973;5623.8594035149035;25039.873205705786;25525.74474255271;25543.48913021729;25745.871270643525;25764.969401223927;2673.1713168917504;2808.354078561942;2831.207261966653;2834.1639216431204;2836.9432611347866;15402.691946161127;15462.768058479132;15501.534953951399;15798.36806527749;16001.519954401387;1518.9088654680754;1741.7561541384216;1781.4566557200178;1791.713325867868;1799.6940520111611;91631.08368916292;114291;114892;117914;120025;10281.691549253534;10382.221333399873;10425.791484170426;10607;10696.465176741132;12698.30159341245;12862.099653024386;12868.871311286954;12870.970322374187;12878.227306361461;8118.512889226666;8295.04606970194;8336.4160809446;8345.833083338299;8435.493870367798;7099.680514376865;8394.370422218359;8506.234438900528;8525.701600443954;8644.827103457896;5754.440215667664;6599.274079851212;6682.431993280528;6783.796486105425;6895.448364130857;1638.4429294040083;1647;1955.6186543624142;1956.491312258823;1960.6176795524736;948.62055177928;1003.7089244119123;1075.913926885863;1079.3038490173951;1109.1236129726658;19508.414747557596;20833.58332833365;21919.01364438604;22251.666225006637;22452.438689032904;933.1741408853214;1081.3403823667556;1082.7455547946226;1117.1118960426481;1121.198343184627;9875.60497580103;11706.483106132222;11727.179097463266;11757.294562326117;11758.118141139448;718907;719863.2088914309;727398;729592;738857;114647.42676286565;127765.3611731954;146422;147101;153247;1014.9035841595037;1036.44550165661;1056.9735756606146;1059.7562560611102;1067.066316972649;19776.703349449483;19817;20170.193192272145;20180.972854072006;20197.6970345442;37681.43477847779;44395;58220;59105.40894590999;60393.79212415815;84338.57830710807;84600;84830;84912.15087849043;86655.13344866723;8171.223733745284;8308.169183081643;8389.370797190357;8398.790030249293;8569.442986205877;10398.68803935883;12814.295213763333;12825.23048617086;13105;13232.801507977196;9140.497272650067;11189.88810111921;11402.714932126763;11416.200865939305;11428.82856757166;10979.451027448436;11042;11086.667399978014;11290.604828830952;11391.316521008435;10709.839352409565;11035.668929932115;11409.771804563696;11642.53429862796;11647.883521165018;7019.227884932551;7100.147982242164;7349.9342595322305;7444.181140074479;8514.10601886793;6396.9040464394;6800.387965082969;7818.452708310364;8138.959305203436;9271.536423178948 08:42:26 INFO - PROCESS | 1641 | |3;mootools.html;1424.843267240604;1451.9564413067599;1462.736707392669;1467.40570069122;1484.0798704803021;7490.88763668544;7834.686612535506;8066.757997260084;8469.57652117394;8505;7986;8217.79455513611;8237.640789269779;8360.163983601637;8361.289290410323;2026.2806703620224;2276.2602154299793;2322.396176993985;2327.325075728037;2332.1137967572386;1920.7695076590758;2288.141946769961;2328.895199716017;2340.063974410241;2344.554534638422;1919.3186418821326;2289.3722672862136;2293.094227780034;2308.7575804540534;2310.90756369745;2231.2079211879714;2261.412032871449;2276.738175109867;2301.631738921781;2301.873396963175;2272.9545409091725;2309.8614083155067;2334.48641298914;2342.9648555271683;2365.0657990093987;8339.45793523419;8379.83240335196;8438.2827459666;8449.873251901226;8450.154984501562;4428.778561071934;5069.188929771219;5174.327337446108;5207.453217412193;5210.270562121306;3282.2614911644687;3354.530365748797;3498.6326435724136;3549.5563054618174;3550.9644903550893;1190.0598527163531;1202.903767698582;1214.2021845686827;1216.738401243737;1234.969125771863;9103.226225770817;10694;10781.676549703521;10782.946085269601;10815.134789216923;4412.602865742098;4594.19601569724;4604.033153037833;4640.396748422683;4692.436907571045;9470.105978804268;9490.098440648128;9517.143457088896;9527.047295270417;9528.761780955532;5233.921491177635;5238.476152384807;5241.187615919539;5242.266082748418;5244.056069907377;4172.472866939344;4603.861884143481;4675.719456832533;4773.689710168828;4788.4253889533475;3763.8118094095184;3801.4487899254455;3863.690904727615;3867.207222519377;3884.8446062157195;1261.6278197931633;1262.1732765038914;1267.233323839064;1277.7316763479587;1278.5105352264507;927.4249965021729;933.2067742419025;939.5349302095507;940.3464592108555;972.0668158567854;5607.495325420732;5714.142878568165;5820.330661973842;5911.940880591139;5929.584929055011;568.7469076261024;572.130361849994;576.702997956053;580.8170426315697;591.3701907468533;3864.632859878307;3867.4972253607416;3955.960440395617;3956.6834653227675;3972.324704800191;7689.846523021592;8602.440841345418;9738.513074346254;10014.198864090855;10053.04496072872;9873.456959867277;9994.950025249973;10016.899831001596;10027.39835609851;10037.447940363205;7388.224236455309;7798.22017798227;7900.735882258926;7907.288344049063;7954.522728636184;5413.485718856702;5430.104032834538;5903.114532820111;5954.255718035246;6002.189704389852;9023.233025192669;9168.679096231599;9224.985251622451;9235.307351948497;9243.260539156718;8880.644774208959;9053.773655658399;9166.129217724305;9222.44665320083;9377.343585948918;6514.967425162844;6719.764808231688;6906.792796216121;6958;7150.785476435715;7758.68965241384;7910.248526389983;7937.563434011185;7952.085510166405;7955.1249362568915;6228.626282423069;7040.401565866959;7145.39264162552;7197.244289349753;7273;6183.288921773874;7011.474139439664;7023;7039;7047.47143964215;6259.280182779039;6341.4292713656;6407.967960160169;6411.51913606472;6412.839679008062;4394.98915249488;4953.628477864103;4980.825671101494;4991.850244492671;5044.419891712353;4932.235503496964;4959.528844759742;5013.24801279811;5086.694798312114;5091.516305950929 08:42:26 INFO - PROCESS | 1641 | |4;prototype.html;315.70323895538195;316.87008326586107;318.71240189633886;357.32465639940534;358.49631268068373;4903;5058;5342.866428339294;5392.137258038714;5498.477644623765;5049;5365.839024829252;5512.574467234424;5525.281713377255;5540.667559946406;3538.203904121565;4318.56814318568;4354.063876266602;4354.586314300136;4392.573792441901;3902.6487616114546;4119.303993447472;4251.979524914013;4325.372820940962;4328.026194106316;4062.878113656595;4215.220184265921;4263.934016495876;4280.764557949312;4292.098659281539;4238.87283381499;4262.190183865071;4273.807678654468;4317.409554267446;4328.156009578133;3647.3981793004386;3812.856143157042;3822.57951625321;3827.4978394768755;3842.5773164951834;4484.663650226246;4582.427196600425;5114.539691427753;5360.946390536123;5361.624686271963;4985.35190425246;4997.350344455234;4998.425181104183;5008.499150084999;5036.219385995177;4872.3909511311085;5705.62913410627;5716.942830571683;5754.884902301972;5886.764529418818;4174.853880114181;4182.351735481006;4215.810288537024;4217.502786510775;4219.43037689914;5214.957008598296;5568.515012712475;5582.581306401996;5997.010493268605;6143.385661433866;3957.604239576077;4233.386159006926;4254.829921771538;4283.528811830696;4301.5698430156735;3515.57813062429;4239.97880010598;4312.37470566766;4347.2176407672705;4350.347447882779;4444.577765112309;4507.977460112678;4573.667429610922;4960.956820191533;5007.824726134568;4766.4995175506765;5265.947340526623;5445.74001789253;5480.725963701799;5902.173695682608;1317.914335568185;1356.9185848849102;1408.5703860322494;1422.9501967431297;1446.7251222267735;930.8178613161383;989.44096585429;992.4666390426808;1004.5830980143305;1008.6383382433802;4542.954570454319;4671.995520963009;5173.40505841833;5268.970342053335;5348.946510534923;4995.225740010232;5031.7987280509105;5248.921266181011;5746.856328591819;5748.626339287933;4772.284157376352;4800.5598320504405;5216.400113986864;5237.240600113038;5237.371515418175;2627.408833012557;2737.75504086647;2858.1425572328217;3002.219422950005;3035.8937437189593;5149.579697542149;5523.7790488380815;5646.774129034875;6012.549058820606;6039.577229593974;5591.769810641652;5597.216389705444;5911.512207797334;5957.761689532457;5977.312609049928;5260.395054568774;6024.518038556905;6026.879462410728;6047.365026672224;6050.5764596479585;5084.737291905067;5183.740812959261;5664.207011018543;5765.4522820332;5799.94200057994;5280.81517146898;5325.520703136582;5970.343262241063;5987.700614969147;5994.670293133745;5113.795448182031;5432.266644003009;5483.177523371526;5497.340319161726;6319.778807741707;3141.246100935805;3899.53205615328;3946.934327731554;3996.960030399659;4004.6596039336982;4792.976035119802;4813.374261346003;4962.404511458648;6108.541859360655;6155.107509411021;5100.69395836251;5202.583793296603;5790.449907258803;5844.649321040752;5912.645241285537;4978.004399120263;5696.686682232517;5908.4047307227665;5922.496587790086;5935.655416110699;4672.32251323563;5503.394626591152;5706.9714651426475;5745.2818397700285;5796.594238403271;5917.142014407801;5922.881542369042;5925.703714814156;5930.915923719728;5932.550587059705;5546.778128874961;5726.226959360433;5760.855978600569;5784.566157538286;5794.101914203306 08:42:26 INFO - PROCESS | 1641 | |5;yui.html;703.8486725354048;708.6598432752279;755.312665474418;828.7886588919824;864.3430992445741;14682.192479413628;14909.850901491009;15266.236688165574;15385.615359616015;15446.150461724601;14347.928260358683;14359.64100897478;14434.783478247835;14507.347169377377;14540.200288984102;871.8822958900531;874.073482108965;880.6653471680752;883.3551507399602;884.4383816276678;683.5830143612393;704.3519961635308;848.5545088828384;861.452977359375;864.5849992003842;926.0045451140052;926.5413620257948;926.9212116970066;932.5523748600676;938.5682586010444;918.4672889723944;921.4885738415144;924.4129977464358;925.1719710858778;928.9303302252358;681.3561184680478;693.6081114170478;696.8989496523027;701.9754308599225;702.7715992302471;13286.26925519096;13664.248466334348;15133.864960128034;15162.924185379114;15405.537833865003;14238.359273832702;14337;14603.70792584153;14649.85028218938;14683;11602.322451189291;11888.940555297255;11899.702507437296;11926.344051077187;11968.940155299168;3137.670544592808;3584.6953008994265;3674.7427680062406;3807.904802379935;3848.1149335652676;101807;103759;104311.47844260806;105494;105792.47103764508;10812.621558245424;11031.393273370042;11182.944085279521;11212;11658.94170529142;18905.7164142538;20264.696029559567;22398.544094633795;23233.419164520685;23245.7675423247;7654.655540500691;7704.345130663844;7734.574598397142;7792.103908050533;7897.289243967955;4210.663146948298;4234.703570750018;4301;4349.934750978738;4399.758013309299;3337.482690182977;3435.3988052091286;3541.061618671054;3638.308721342936;3645.5989841117457;630.6815058395573;645.0356716708551;647.439964430764;648.3775575447623;663.1478550063242;671.1542268857671;703.8064532253671;703.8697840899348;712.8111050571603;714.3071220915704;10261.486925653688;10261.589536418609;10362.948185259025;10634.255602107933;10689.83965240522;656.2715385921623;669.136813510566;678.4199509419474;690.3823962436895;691.405391363427;4620.4686461056735;4935.136351138536;4938.160512712848;4940.530649588283;4946.727929963887;101363.49318253361;113069.43465282621;113801;114374.42812785883;119805.40097299458;96853;98475;98792.5060374708;99789.50105249428;100680.49659751654;73999;88363;88951.55524222337;90576.547117264;91482;9371.390859594105;9608.13526782565;9618.711438656852;9619.807603848023;9652.806943861224;50938.74530627323;54426;56003.43996560145;56902;57122.42877571337;61855.76288474295;62975.68512157593;66489.33510664832;69333.65333173504;73828.89256661262;13029.348532573144;13078.934605326913;13323.733525329633;13399.263040532864;13866.584002479942;14309.356078976263;14499.420023199376;14696;14833.703325933637;15465.60809527148;13550.322483875963;13610.795838062635;14002.929985350009;14212.644683882985;14296;11479.827802583135;13587.599570282186;13648.795268071186;13675.521356752466;13775.586732398044;13364.799528006894;13494.865051349361;13527.932360338134;13717.725645486835;14063.347245782721;10779.461026948464;10813.62152324665;11121.555137794398;11126.888731112585;11280.717982050186;9380.390274632127;9649.324547281904;10589.682309530726;10863.782724345627;10865.619703310345 08:42:26 INFO - PROCESS | 1641 | __end_tp_report 08:42:26 INFO - PROCESS | 1641 | __start_cc_report 08:42:26 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,377 08:42:26 INFO - PROCESS | 1641 | __end_cc_report 08:42:26 INFO - PROCESS | 1641 | __startTimestamp1484152946935__endTimestamp 08:42:26 INFO - PROCESS | 1641 | 08:42:26 INFO - PROCESS | 1641 | ------- Summary: start ------- 08:42:26 INFO - PROCESS | 1641 | Number of tests: 6 08:42:26 INFO - PROCESS | 1641 | 08:42:26 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5717.76 Median:5877.79 stddev:660.14 (11.2%) stddev-sans-first:634.66 08:42:26 INFO - PROCESS | 1641 | Values: 3313.6 3324.5 3334.7 3367.4 3373.0 4571.3 5571.2 5614.4 5620.2 5658.5 5632.6 5657.6 5715.0 5770.6 5779.6 4463.2 5118.1 5173.5 5271.4 5274.7 5210.6 5215.9 5309.8 5330.1 5348.2 5149.4 5266.3 5268.1 5282.0 5342.9 5156.1 5201.1 5207.5 5264.3 5292.2 5178.8 5207.0 5219.5 5228.8 5282.2 5588.8 5630.9 5671.5 5756.2 5763.0 6139.6 6146.2 6181.5 6206.9 6263.6 6300.8 6302.1 6322.1 6327.1 6342.4 5818.5 5834.0 5857.0 5872.4 5877.8 6150.8 6271.2 6274.2 6303.7 6325.4 6133.2 6157.2 6176.6 6222.8 6229.8 5678.2 6259.5 6278.5 6292.5 6316.2 4532.4 4786.0 6094.5 6231.6 6276.0 5131.0 6211.6 6280.6 6297.2 6316.7 5031.5 5032.3 5041.4 5047.2 5079.9 4402.9 4433.3 4444.0 4453.3 4468.6 5711.9 5718.4 5749.6 5768.6 5840.1 6241.9 6311.1 6317.1 6320.1 6329.8 5518.3 5630.0 5771.7 5822.1 5849.6 5358.5 5387.6 5414.7 5443.7 5468.3 6257.2 6266.5 6289.3 6382.5 6395.6 6296.9 6301.7 6306.7 6308.3 6314.6 5583.2 6216.8 6271.7 6418.2 6421.5 6124.4 6142.6 6148.6 6166.7 6266.0 6249.7 6304.2 6305.7 6306.0 6327.8 6073.5 6184.4 6187.0 6190.4 6205.9 5946.4 6015.9 6018.1 6020.5 6079.0 5246.0 5674.3 5873.3 5947.1 6032.0 5958.4 6015.0 6031.1 6051.1 6138.3 5894.5 5911.6 5959.9 5961.7 6038.5 08:42:26 INFO - PROCESS | 1641 | 08:42:26 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:69865.56 Median:35194.80 stddev:158659.22 (450.8%) stddev-sans-first:159018.77 08:42:26 INFO - PROCESS | 1641 | Values: 475.8 481.7 501.9 531.9 537.6 32339.8 40826.0 40942.6 41070.4 41184.0 41602.8 42128.0 42169.2 42267.7 43158.6 8428.3 8628.4 9311.6 9509.8 11369.3 9482.9 11534.5 11541.2 11553.1 11589.3 11509.4 11563.9 11579.8 11597.6 11630.9 11466.8 11530.7 11531.9 11540.9 11546.4 11399.8 11460.3 11465.5 11491.9 11494.5 40838.6 41066.6 41317.6 41345.6 41486.2 3628.6 3958.9 4086.6 4127.3 4235.3 25317.6 28141.4 28459.0 30388.7 31322.9 3149.6 3185.0 3233.5 3252.1 3303.1 387743.0 415649.0 456780.0 457527.7 458648.0 61553.0 61925.1 62144.0 62173.7 62232.4 64405.0 64661.7 65120.0 65153.0 65315.0 15629.1 15860.4 16083.0 16085.0 16104.2 11118.3 11345.3 12103.3 12388.1 12423.0 15820.9 16226.7 17316.1 17464.6 17506.8 6437.7 6839.1 6859.2 6892.2 6899.4 876.8 885.0 894.4 928.4 928.6 28918.7 30013.9 30096.8 30293.2 30353.0 866.3 1048.9 1068.9 1076.9 1103.2 15060.5 15083.5 15560.5 15587.8 15944.6 739805.0 761498.0 763671.0 764216.0 798077.0 52652.2 53259.0 53343.7 53452.0 53489.0 1402.0 1412.8 1441.3 1447.8 1452.5 27389.5 28154.3 28811.7 28905.0 29431.7 46243.0 46291.8 46673.4 46690.8 46860.8 517585.9 518945.0 519656.0 522341.0 522557.0 24575.4 24589.5 24655.8 24900.5 24956.4 32543.0 42572.0 43492.6 43543.8 43638.3 28620.1 35160.6 35577.6 36042.3 36787.8 30505.5 32621.2 35686.3 36502.6 36755.4 34817.3 35228.9 35357.3 35588.8 35613.3 30208.0 31535.7 31581.7 31686.4 31702.7 20329.8 22752.0 22835.9 24279.8 24556.0 08:42:26 INFO - PROCESS | 1641 | 08:42:26 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:38887.68 Median:25282.81 stddev:120500.90 (476.6%) stddev-sans-first:120804.24 08:42:26 INFO - PROCESS | 1641 | Values: 377.6 384.0 388.6 396.2 414.8 12623.6 15236.7 19364.2 20606.6 20636.9 19597.5 19771.6 20180.2 21303.3 21557.5 5596.9 5616.0 5764.9 5832.5 5851.7 5677.7 5788.5 5789.6 5798.3 5803.7 4046.9 4977.6 6337.4 6400.0 6444.5 5020.7 5468.2 6182.8 6394.7 6401.1 5514.8 5555.8 5595.1 5623.2 5623.9 25039.9 25525.7 25543.5 25745.9 25765.0 2673.2 2808.4 2831.2 2834.2 2836.9 15402.7 15462.8 15501.5 15798.4 16001.5 1518.9 1741.8 1781.5 1791.7 1799.7 91631.1 114291.0 114892.0 117914.0 120025.0 10281.7 10382.2 10425.8 10607.0 10696.5 12698.3 12862.1 12868.9 12871.0 12878.2 8118.5 8295.0 8336.4 8345.8 8435.5 7099.7 8394.4 8506.2 8525.7 8644.8 5754.4 6599.3 6682.4 6783.8 6895.4 1638.4 1647.0 1955.6 1956.5 1960.6 948.6 1003.7 1075.9 1079.3 1109.1 19508.4 20833.6 21919.0 22251.7 22452.4 933.2 1081.3 1082.7 1117.1 1121.2 9875.6 11706.5 11727.2 11757.3 11758.1 718907.0 719863.2 727398.0 729592.0 738857.0 114647.4 127765.4 146422.0 147101.0 153247.0 1014.9 1036.4 1057.0 1059.8 1067.1 19776.7 19817.0 20170.2 20181.0 20197.7 37681.4 44395.0 58220.0 59105.4 60393.8 84338.6 84600.0 84830.0 84912.2 86655.1 8171.2 8308.2 8389.4 8398.8 8569.4 10398.7 12814.3 12825.2 13105.0 13232.8 9140.5 11189.9 11402.7 11416.2 11428.8 10979.5 11042.0 11086.7 11290.6 11391.3 10709.8 11035.7 11409.8 11642.5 11647.9 7019.2 7100.1 7349.9 7444.2 8514.1 6396.9 6800.4 7818.5 8139.0 9271.5 08:42:26 INFO - PROCESS | 1641 | 08:42:26 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5383.44 Median:5239.83 stddev:2920.13 (55.7%) stddev-sans-first:2913.16 08:42:26 INFO - PROCESS | 1641 | Values: 1424.8 1452.0 1462.7 1467.4 1484.1 7490.9 7834.7 8066.8 8469.6 8505.0 7986.0 8217.8 8237.6 8360.2 8361.3 2026.3 2276.3 2322.4 2327.3 2332.1 1920.8 2288.1 2328.9 2340.1 2344.6 1919.3 2289.4 2293.1 2308.8 2310.9 2231.2 2261.4 2276.7 2301.6 2301.9 2273.0 2309.9 2334.5 2343.0 2365.1 8339.5 8379.8 8438.3 8449.9 8450.2 4428.8 5069.2 5174.3 5207.5 5210.3 3282.3 3354.5 3498.6 3549.6 3551.0 1190.1 1202.9 1214.2 1216.7 1235.0 9103.2 10694.0 10781.7 10782.9 10815.1 4412.6 4594.2 4604.0 4640.4 4692.4 9470.1 9490.1 9517.1 9527.0 9528.8 5233.9 5238.5 5241.2 5242.3 5244.1 4172.5 4603.9 4675.7 4773.7 4788.4 3763.8 3801.4 3863.7 3867.2 3884.8 1261.6 1262.2 1267.2 1277.7 1278.5 927.4 933.2 939.5 940.3 972.1 5607.5 5714.1 5820.3 5911.9 5929.6 568.7 572.1 576.7 580.8 591.4 3864.6 3867.5 3956.0 3956.7 3972.3 7689.8 8602.4 9738.5 10014.2 10053.0 9873.5 9995.0 10016.9 10027.4 10037.4 7388.2 7798.2 7900.7 7907.3 7954.5 5413.5 5430.1 5903.1 5954.3 6002.2 9023.2 9168.7 9225.0 9235.3 9243.3 8880.6 9053.8 9166.1 9222.4 9377.3 6515.0 6719.8 6906.8 6958.0 7150.8 7758.7 7910.2 7937.6 7952.1 7955.1 6228.6 7040.4 7145.4 7197.2 7273.0 6183.3 7011.5 7023.0 7039.0 7047.5 6259.3 6341.4 6408.0 6411.5 6412.8 4395.0 4953.6 4980.8 4991.9 5044.4 4932.2 4959.5 5013.2 5086.7 5091.5 08:42:26 INFO - PROCESS | 1641 | 08:42:26 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4663.60 Median:5092.72 stddev:1382.96 (27.2%) stddev-sans-first:1347.78 08:42:26 INFO - PROCESS | 1641 | Values: 315.7 316.9 318.7 357.3 358.5 4903.0 5058.0 5342.9 5392.1 5498.5 5049.0 5365.8 5512.6 5525.3 5540.7 3538.2 4318.6 4354.1 4354.6 4392.6 3902.6 4119.3 4252.0 4325.4 4328.0 4062.9 4215.2 4263.9 4280.8 4292.1 4238.9 4262.2 4273.8 4317.4 4328.2 3647.4 3812.9 3822.6 3827.5 3842.6 4484.7 4582.4 5114.5 5360.9 5361.6 4985.4 4997.4 4998.4 5008.5 5036.2 4872.4 5705.6 5716.9 5754.9 5886.8 4174.9 4182.4 4215.8 4217.5 4219.4 5215.0 5568.5 5582.6 5997.0 6143.4 3957.6 4233.4 4254.8 4283.5 4301.6 3515.6 4240.0 4312.4 4347.2 4350.3 4444.6 4508.0 4573.7 4961.0 5007.8 4766.5 5265.9 5445.7 5480.7 5902.2 1317.9 1356.9 1408.6 1423.0 1446.7 930.8 989.4 992.5 1004.6 1008.6 4543.0 4672.0 5173.4 5269.0 5348.9 4995.2 5031.8 5248.9 5746.9 5748.6 4772.3 4800.6 5216.4 5237.2 5237.4 2627.4 2737.8 2858.1 3002.2 3035.9 5149.6 5523.8 5646.8 6012.5 6039.6 5591.8 5597.2 5911.5 5957.8 5977.3 5260.4 6024.5 6026.9 6047.4 6050.6 5084.7 5183.7 5664.2 5765.5 5799.9 5280.8 5325.5 5970.3 5987.7 5994.7 5113.8 5432.3 5483.2 5497.3 6319.8 3141.2 3899.5 3946.9 3997.0 4004.7 4793.0 4813.4 4962.4 6108.5 6155.1 5100.7 5202.6 5790.4 5844.6 5912.6 4978.0 5696.7 5908.4 5922.5 5935.7 4672.3 5503.4 5707.0 5745.3 5796.6 5917.1 5922.9 5925.7 5930.9 5932.6 5546.8 5726.2 5760.9 5784.6 5794.1 08:42:26 INFO - PROCESS | 1641 | 08:42:26 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:21356.43 Median:3660.17 stddev:31320.47 (855.7%) stddev-sans-first:31369.94 08:42:26 INFO - PROCESS | 1641 | Values: 703.8 708.7 755.3 828.8 864.3 14682.2 14909.9 15266.2 15385.6 15446.2 14347.9 14359.6 14434.8 14507.3 14540.2 871.9 874.1 880.7 883.4 884.4 683.6 704.4 848.6 861.5 864.6 926.0 926.5 926.9 932.6 938.6 918.5 921.5 924.4 925.2 928.9 681.4 693.6 696.9 702.0 702.8 13286.3 13664.2 15133.9 15162.9 15405.5 14238.4 14337.0 14603.7 14649.9 14683.0 11602.3 11888.9 11899.7 11926.3 11968.9 3137.7 3584.7 3674.7 3807.9 3848.1 101807.0 103759.0 104311.5 105494.0 105792.5 10812.6 11031.4 11182.9 11212.0 11658.9 18905.7 20264.7 22398.5 23233.4 23245.8 7654.7 7704.3 7734.6 7792.1 7897.3 4210.7 4234.7 4301.0 4349.9 4399.8 3337.5 3435.4 3541.1 3638.3 3645.6 630.7 645.0 647.4 648.4 663.1 671.2 703.8 703.9 712.8 714.3 10261.5 10261.6 10362.9 10634.3 10689.8 656.3 669.1 678.4 690.4 691.4 4620.5 4935.1 4938.2 4940.5 4946.7 101363.5 113069.4 113801.0 114374.4 119805.4 96853.0 98475.0 98792.5 99789.5 100680.5 73999.0 88363.0 88951.6 90576.5 91482.0 9371.4 9608.1 9618.7 9619.8 9652.8 50938.7 54426.0 56003.4 56902.0 57122.4 61855.8 62975.7 66489.3 69333.7 73828.9 13029.3 13078.9 13323.7 13399.3 13866.6 14309.4 14499.4 14696.0 14833.7 15465.6 13550.3 13610.8 14002.9 14212.6 14296.0 11479.8 13587.6 13648.8 13675.5 13775.6 13364.8 13494.9 13527.9 13717.7 14063.3 10779.5 10813.6 11121.6 11126.9 11280.7 9380.4 9649.3 10589.7 10863.8 10865.6 08:42:26 INFO - PROCESS | 1641 | -------- Summary: end -------- 08:42:26 INFO - PROCESS | 1641 | 08:42:27 INFO - TEST-INFO | 1641: exit 0 08:42:27 INFO - TEST-OK | dromaeo_css | took 1115584ms 08:42:27 INFO - TEST-START | kraken 08:42:27 INFO - operating with platform_type : mac_ 08:42:27 INFO - Initialising browser for kraken test... 08:42:27 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/tmp0zBYsB/profile http://localhost:49240/getInfo.html) 08:42:30 INFO - PROCESS | 1654 | 2017-01-11 08:42:30.135 plugin-container[1655:10549] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a47, name = 'com.apple.tsm.portname' 08:42:30 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:42:30 INFO - PROCESS | 1654 | 2017-01-11 08:42:30.138 plugin-container[1655:10549] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 08:42:30 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:42:30 INFO - PROCESS | 1654 | 2017-01-11 08:42:30.138 plugin-container[1655:10549] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:42:30 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 08:42:30 INFO - PROCESS | 1654 | colorDepth:24 08:42:30 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 08:42:30 INFO - PROCESS | 1654 | __metrics 08:42:30 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 08:42:30 INFO - TEST-INFO | 1654: exit 0 08:42:30 INFO - Browser initialized. 08:42:30 INFO - Running cycle 1/1 for kraken test... 08:42:30 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/tmp0zBYsB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:42:33 INFO - PROCESS | 1656 | 2017-01-11 08:42:33.213 plugin-container[1657:10664] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 08:42:33 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:42:33 INFO - PROCESS | 1656 | 2017-01-11 08:42:33.214 plugin-container[1657:10664] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x474b, name = 'com.apple.CFPasteboardClient' 08:42:33 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:42:33 INFO - PROCESS | 1656 | 2017-01-11 08:42:33.214 plugin-container[1657:10664] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:42:43 INFO - PROCESS | 1656 | RSS: Main: 232779776 08:42:43 INFO - PROCESS | 1656 | 08:42:46 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 08:42:46 INFO - PROCESS | 1656 | RSS: Main: 245510144 08:42:46 INFO - PROCESS | 1656 | 08:42:49 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 08:42:50 INFO - PROCESS | 1656 | RSS: Main: 253603840 08:42:50 INFO - PROCESS | 1656 | 08:42:53 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 08:42:54 INFO - PROCESS | 1656 | RSS: Main: 256180224 08:42:54 INFO - PROCESS | 1656 | 08:42:55 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 08:42:56 INFO - PROCESS | 1656 | RSS: Main: 258437120 08:42:56 INFO - PROCESS | 1656 | 08:42:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:42:59 INFO - PROCESS | 1656 | RSS: Main: 259047424 08:42:59 INFO - PROCESS | 1656 | 08:43:02 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 08:43:02 INFO - PROCESS | 1656 | RSS: Main: 265101312 08:43:02 INFO - PROCESS | 1656 | 08:43:05 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 08:43:06 INFO - PROCESS | 1656 | RSS: Main: 265035776 08:43:06 INFO - PROCESS | 1656 | 08:43:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 08:43:09 INFO - PROCESS | 1656 | RSS: Main: 263954432 08:43:09 INFO - PROCESS | 1656 | 08:43:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:43:12 INFO - PROCESS | 1656 | RSS: Main: 263385088 08:43:12 INFO - PROCESS | 1656 | 08:43:14 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:43:14 INFO - PROCESS | 1656 | RSS: Main: 263434240 08:43:14 INFO - PROCESS | 1656 | 08:43:16 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:43:17 INFO - PROCESS | 1656 | RSS: Main: 263462912 08:43:17 INFO - PROCESS | 1656 | 08:43:20 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:43:21 INFO - PROCESS | 1656 | RSS: Main: 263442432 08:43:21 INFO - PROCESS | 1656 | 08:43:24 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:43:24 INFO - PROCESS | 1656 | RSS: Main: 263811072 08:43:24 INFO - PROCESS | 1656 | 08:43:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 08:43:26 INFO - PROCESS | 1656 | RSS: Main: 265560064 08:43:26 INFO - PROCESS | 1656 | 08:43:26 INFO - PROCESS | 1656 | __start_tp_report 08:43:26 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 08:43:26 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 08:43:26 INFO - PROCESS | 1656 | |i|pagename|runs| 08:43:26 INFO - PROCESS | 1656 | |0;ai-astar;107;173;110;173;173;164;120;117;119;119 08:43:26 INFO - PROCESS | 1656 | |1;audio-beat-detection;147;111;111;166;139;112;111;114;131;112 08:43:26 INFO - PROCESS | 1656 | |2;audio-dft;196;204;204;219;195;198;226;260;200;194 08:43:26 INFO - PROCESS | 1656 | |3;audio-fft;82;60;58;83;60;60;60;60;71;62 08:43:26 INFO - PROCESS | 1656 | |4;audio-oscillator;88;71;85;73;70;70;81;65;70;82 08:43:26 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;92;76;118;131;91;113;108;120;136;112 08:43:26 INFO - PROCESS | 1656 | |6;imaging-darkroom;98;96;99;98;96;97;95;95;98;97 08:43:26 INFO - PROCESS | 1656 | |7;imaging-desaturate;149;143;91;97;92;94;95;92;110;92 08:43:26 INFO - PROCESS | 1656 | |8;json-parse-financial;60;61;61;61;64;63;64;62;63;63 08:43:26 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;71;76;83;79;70;70;70;72;72;73 08:43:26 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;88;92;92;92;90;90;91;89;91;88 08:43:26 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;141;146;134;141;141;141;139;140;188;142 08:43:26 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;175;174;170;171;172;174;175;211;206;215 08:43:26 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;69;71;67;69;83;91;88;75;68;68 08:43:26 INFO - PROCESS | 1656 | __end_tp_report 08:43:26 INFO - PROCESS | 1656 | __start_cc_report 08:43:26 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,926 08:43:26 INFO - PROCESS | 1656 | __end_cc_report 08:43:26 INFO - PROCESS | 1656 | __startTimestamp1484153006798__endTimestamp 08:43:26 INFO - PROCESS | 1656 | 08:43:26 INFO - PROCESS | 1656 | ------- Summary: start ------- 08:43:26 INFO - PROCESS | 1656 | Number of tests: 14 08:43:26 INFO - PROCESS | 1656 | 08:43:26 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:137.50 Median:142.00 stddev:29.02 (20.4%) stddev-sans-first:28.61 08:43:26 INFO - PROCESS | 1656 | Values: 107.0 173.0 110.0 173.0 173.0 164.0 120.0 117.0 119.0 119.0 08:43:26 INFO - PROCESS | 1656 | 08:43:26 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:125.40 Median:122.50 stddev:19.56 (16.0%) stddev-sans-first:19.12 08:43:26 INFO - PROCESS | 1656 | Values: 147.0 111.0 111.0 166.0 139.0 112.0 111.0 114.0 131.0 112.0 08:43:26 INFO - PROCESS | 1656 | 08:43:26 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:209.60 Median:204.00 stddev:20.62 (10.1%) stddev-sans-first:21.28 08:43:26 INFO - PROCESS | 1656 | Values: 196.0 204.0 204.0 219.0 195.0 198.0 226.0 260.0 200.0 194.0 08:43:26 INFO - PROCESS | 1656 | 08:43:26 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:65.60 Median:61.00 stddev:9.59 (15.7%) stddev-sans-first:8.14 08:43:26 INFO - PROCESS | 1656 | Values: 82.0 60.0 58.0 83.0 60.0 60.0 60.0 60.0 71.0 62.0 08:43:26 INFO - PROCESS | 1656 | 08:43:26 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:77.00 stddev:7.79 (10.1%) stddev-sans-first:6.83 08:43:26 INFO - PROCESS | 1656 | Values: 88.0 71.0 85.0 73.0 70.0 70.0 81.0 65.0 70.0 82.0 08:43:26 INFO - PROCESS | 1656 | 08:43:26 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:109.70 Median:133.50 stddev:18.67 (14.0%) stddev-sans-first:18.67 08:43:26 INFO - PROCESS | 1656 | Values: 92.0 76.0 118.0 131.0 91.0 113.0 108.0 120.0 136.0 112.0 08:43:26 INFO - PROCESS | 1656 | 08:43:26 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:96.90 Median:97.50 stddev:1.37 (1.4%) stddev-sans-first:1.39 08:43:26 INFO - PROCESS | 1656 | Values: 98.0 96.0 99.0 98.0 96.0 97.0 95.0 95.0 98.0 97.0 08:43:26 INFO - PROCESS | 1656 | 08:43:26 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:105.50 Median:92.00 stddev:22.09 (24.0%) stddev-sans-first:16.91 08:43:26 INFO - PROCESS | 1656 | Values: 149.0 143.0 91.0 97.0 92.0 94.0 95.0 92.0 110.0 92.0 08:43:26 INFO - PROCESS | 1656 | 08:43:26 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:62.20 Median:63.00 stddev:1.40 (2.2%) stddev-sans-first:1.24 08:43:26 INFO - PROCESS | 1656 | Values: 60.0 61.0 61.0 61.0 64.0 63.0 64.0 62.0 63.0 63.0 08:43:26 INFO - PROCESS | 1656 | 08:43:26 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:73.60 Median:72.50 stddev:4.40 (6.1%) stddev-sans-first:4.57 08:43:26 INFO - PROCESS | 1656 | Values: 71.0 76.0 83.0 79.0 70.0 70.0 70.0 72.0 72.0 73.0 08:43:26 INFO - PROCESS | 1656 | 08:43:26 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:91.00 stddev:1.57 (1.7%) stddev-sans-first:1.42 08:43:26 INFO - PROCESS | 1656 | Values: 88.0 92.0 92.0 92.0 90.0 90.0 91.0 89.0 91.0 88.0 08:43:26 INFO - PROCESS | 1656 | 08:43:26 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:141.00 stddev:15.29 (10.8%) stddev-sans-first:16.14 08:43:26 INFO - PROCESS | 1656 | Values: 141.0 146.0 134.0 141.0 141.0 141.0 139.0 140.0 188.0 142.0 08:43:26 INFO - PROCESS | 1656 | 08:43:26 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.30 Median:175.00 stddev:18.39 (10.5%) stddev-sans-first:19.20 08:43:26 INFO - PROCESS | 1656 | Values: 175.0 174.0 170.0 171.0 172.0 174.0 175.0 211.0 206.0 215.0 08:43:26 INFO - PROCESS | 1656 | 08:43:26 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.90 Median:73.00 stddev:9.06 (12.4%) stddev-sans-first:9.36 08:43:26 INFO - PROCESS | 1656 | Values: 69.0 71.0 67.0 69.0 83.0 91.0 88.0 75.0 68.0 68.0 08:43:26 INFO - PROCESS | 1656 | -------- Summary: end -------- 08:43:26 INFO - PROCESS | 1656 | 08:43:27 INFO - TEST-INFO | 1656: exit 0 08:43:27 INFO - TEST-OK | kraken | took 60120ms 08:43:27 INFO - SUITE-END | took 1175s 08:43:27 INFO - Completed test suite (00:19:35) 08:43:27 INFO - javascript benchmark 08:43:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7316.718849391408, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3313.5526703894952, 3324.5179448979916, 3334.733221342293, 3367.393869103562, 3372.999999999999, 4571.291449825274, 5571.164325351199, 5614.438556144389, 5620.241267428901, 5658.462446067627, 5632.633878797876, 5657.603967722261, 5714.971425142869, 5770.567207459437, 5779.595428320019, 4463.241248987663, 5118.078745825741, 5173.508516690908, 5271.42014378418, 5274.709890955995, 5210.609204309692, 5215.921761173586, 5309.814156504523, 5330.067238233313, 5348.197770334462, 5149.407818100929, 5266.262723218752, 5268.078086334876, 5281.996420680059, 5342.91985620216, 5156.149235376159, 5201.115810312256, 5207.531322180984, 5264.263003179558, 5292.153255479144, 5178.792848286064, 5207.0, 5219.530242278175, 5228.79084836606, 5282.154855223184, 5588.804391846306, 5630.859228519279, 5671.461211184972, 5756.165356023395, 5763.0, 6139.600925939844, 6146.200993870768, 6181.536384771115, 6206.906896396557, 6263.6241825490615, 6300.810975670737, 6302.149209856657, 6322.051692246154, 6327.082573026932, 6342.429181373653, 5818.505427038707, 5834.0, 5857.004309267435, 5872.412758724179, 5877.794277200277, 6150.846228844314, 6271.184745982993, 6274.1843560336865, 6303.716332765037, 6325.430711236011, 6133.233345831771, 6157.199564056644, 6176.598521096114, 6222.81331560054, 6229.750809967551, 5678.233438485834, 6259.530535209878, 6278.466330361879, 6292.4966002719675, 6316.210473690789, 4532.388127602731, 4786.042791441726, 6094.542909281821, 6231.594946328474, 6275.96862015687, 5130.974345128325, 6211.565190436626, 6280.623162610258, 6297.2128483939505, 6316.684165791692, 5031.547160755549, 5032.270320803463, 5041.369828771403, 5047.217681259411, 5079.898402032012, 4402.933955990663, 4433.334999749999, 4444.0, 4453.309736990772, 4468.642508599304, 5711.914321285184, 5718.428157184248, 5749.597528172988, 5768.625039372428, 5840.1239814027385, 6241.937580624226, 6311.147995020705, 6317.1156038154695, 6320.051992201206, 6329.841753956188, 5518.310211223597, 5630.0, 5771.740271687784, 5822.068469045017, 5849.64902105875, 5358.517733404013, 5387.595930305243, 5414.675119492843, 5443.673379597237, 5468.316460442445, 6257.155284036688, 6266.530010249249, 6289.339619340087, 6382.489400848014, 6395.584287021329, 6296.874062518632, 6301.747930082929, 6306.65313407767, 6308.250172315762, 6314.55798094129, 5583.2462617547735, 6216.7824126155365, 6271.749130034747, 6418.1656384669695, 6421.518386121153, 6124.350421260821, 6142.590862071414, 6148.647297594522, 6166.722497487534, 6265.968670156803, 6249.6562689052535, 6304.18045654062, 6305.653189074645, 6305.96847015762, 6327.841803954938, 6073.514118870391, 6184.350643182401, 6186.969065154645, 6190.350013248544, 6205.937940620536, 5946.43508866657, 6015.87968240624, 6018.067199584071, 6020.518358531394, 6078.969605151946, 5246.029484545371, 5674.2623458950075, 5873.2658417697785, 5947.1079338099635, 6032.034874420072, 5958.433948774859, 6014.969925150346, 6031.09533569968, 6051.062085376773, 6138.324784273637, 5894.469497745224, 5911.615744976562, 5959.880802384015, 5961.731722072431, 6038.547108966757], "value": 5676.959260438129, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.7549861821163, 481.6580228038093, 501.8532652888151, 531.9255304257402, 537.5618870620447, 32339.838300808493, 40826.0, 40942.59057409425, 41070.38394424086, 41184.0, 41602.751917442525, 42128.0, 42169.156616867724, 42267.731968040935, 43158.56841431585, 8428.32573394127, 8628.396012279143, 9311.627534898596, 9509.80980380392, 11369.2609980351, 9482.857757133648, 11534.538618455252, 11541.249818761795, 11553.075753939665, 11589.304641721481, 11509.42452877357, 11563.942180289087, 11579.768404631903, 11597.594084207054, 11630.883691163064, 11466.827997580043, 11530.654080377519, 11531.942340288246, 11540.884591154065, 11546.364949927793, 11399.772004559862, 11460.312381257152, 11465.484053217626, 11491.885081149165, 11494.482748276349, 40838.59161408407, 41066.589334106575, 41317.58682413198, 41345.58654413478, 41486.1702765943, 3628.6189950055127, 3958.8812335629977, 4086.570910054461, 4127.318992366256, 4235.2800023996, 25317.62023569648, 28141.43717125646, 28459.0, 30388.69611303903, 31322.903698370676, 3149.6062992125985, 3185.044486654018, 3233.514972754082, 3252.08941496379, 3303.0586282909494, 387743.0, 415649.0, 456780.0, 457527.71236143936, 458648.0, 61553.0, 61925.07112393318, 62144.0, 62173.689131554056, 62232.377676223565, 64405.0, 64661.67669161624, 65120.0, 65153.0, 65315.0, 15629.06225626466, 15860.365585376456, 16083.0, 16085.0, 16104.19479026044, 11118.277311974696, 11345.319280843176, 12103.273803571814, 12388.070894682935, 12423.0, 15820.92089539545, 16226.675466490604, 17316.134193290283, 17464.5633859152, 17506.824931750773, 6437.742490300429, 6839.1451068616425, 6859.2454829968665, 6892.207396149507, 6899.448044156456, 876.8114855306139, 884.9867251991225, 894.4454438248328, 928.4197376639601, 928.6192661008954, 28918.710812892023, 30013.949511766983, 30096.806387225548, 30293.242668933013, 30353.0, 866.2982983783156, 1048.905598496139, 1068.9198310126772, 1076.8546246256813, 1103.2497901426946, 15060.54818355451, 15083.47207847742, 15560.533184004498, 15587.766183507256, 15944.601384965468, 739805.0, 761498.0, 763671.0, 764216.0, 798077.0, 52652.210216846775, 53259.0, 53343.73328133334, 53452.0, 53489.0, 1402.0466083063616, 1412.7598308287616, 1441.315375196794, 1447.8407375188722, 1452.5279284232668, 27389.452210956068, 28154.2961425966, 28811.71188288174, 28905.0, 29431.705682942895, 46243.0, 46291.768541158424, 46673.36643217471, 46690.83272918204, 46860.7656961713, 517585.8844940409, 518945.0, 519656.0, 522341.0, 522557.0, 24575.385615359042, 24589.50820983606, 24655.753442465346, 24900.501989960463, 24956.37609059788, 32543.0, 42572.0, 43492.56507434885, 43543.782281088395, 43638.34542481929, 28620.14139575816, 35160.64839351676, 35577.644223557436, 36042.27915441729, 36787.816060919526, 30505.54241686421, 32621.205833679374, 35686.28627427489, 36502.634973649925, 36755.44866827053, 34817.30365392729, 35228.94313170609, 35357.292854143285, 35588.82205589059, 35613.287734245685, 30208.0, 31535.684643153276, 31581.684183157875, 31686.366272674877, 31702.682973169973, 20329.79670203338, 22752.0, 22835.88582057079, 24279.75720242775, 24556.0], "value": 19728.95715564405, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.57522786864774, 383.9731218814682, 388.61708824681153, 396.2174704957709, 414.81776935733166, 12623.62129136125, 15236.695266094672, 19364.225430982762, 20606.587868242626, 20636.89681551592, 19597.51006224845, 19771.604567908635, 20180.192792288326, 21303.25438609649, 21557.46106347342, 5596.916046259309, 5616.0, 5764.855878603047, 5832.475077243069, 5851.707414629252, 5677.659340439566, 5788.507976821975, 5789.565782566325, 5798.3331916829475, 5803.680797556132, 4046.8785936421955, 4977.576905962997, 6337.429631333179, 6400.03999400089, 6444.548881578291, 5020.723860187688, 5468.179773034057, 6182.845428864314, 6394.744210231585, 6401.103845461639, 5514.834554963358, 5555.833325000256, 5595.132754423071, 5623.212750214973, 5623.8594035149035, 25039.873205705786, 25525.74474255271, 25543.48913021729, 25745.871270643525, 25764.969401223927, 2673.1713168917504, 2808.354078561942, 2831.207261966653, 2834.1639216431204, 2836.9432611347866, 15402.691946161127, 15462.768058479132, 15501.534953951399, 15798.36806527749, 16001.519954401387, 1518.9088654680754, 1741.7561541384216, 1781.4566557200178, 1791.713325867868, 1799.6940520111611, 91631.08368916292, 114291.0, 114892.0, 117914.0, 120025.0, 10281.691549253534, 10382.221333399873, 10425.791484170426, 10607.0, 10696.465176741132, 12698.30159341245, 12862.099653024386, 12868.871311286954, 12870.970322374187, 12878.227306361461, 8118.512889226666, 8295.04606970194, 8336.4160809446, 8345.833083338299, 8435.493870367798, 7099.680514376865, 8394.370422218359, 8506.234438900528, 8525.701600443954, 8644.827103457896, 5754.440215667664, 6599.274079851212, 6682.431993280528, 6783.796486105425, 6895.448364130857, 1638.4429294040083, 1647.0, 1955.6186543624142, 1956.491312258823, 1960.6176795524736, 948.62055177928, 1003.7089244119123, 1075.913926885863, 1079.3038490173951, 1109.1236129726658, 19508.414747557596, 20833.58332833365, 21919.01364438604, 22251.666225006637, 22452.438689032904, 933.1741408853214, 1081.3403823667556, 1082.7455547946226, 1117.1118960426481, 1121.198343184627, 9875.60497580103, 11706.483106132222, 11727.179097463266, 11757.294562326117, 11758.118141139448, 718907.0, 719863.2088914309, 727398.0, 729592.0, 738857.0, 114647.42676286565, 127765.3611731954, 146422.0, 147101.0, 153247.0, 1014.9035841595037, 1036.44550165661, 1056.9735756606146, 1059.7562560611102, 1067.066316972649, 19776.703349449483, 19817.0, 20170.193192272145, 20180.972854072006, 20197.6970345442, 37681.43477847779, 44395.0, 58220.0, 59105.40894590999, 60393.79212415815, 84338.57830710807, 84600.0, 84830.0, 84912.15087849043, 86655.13344866723, 8171.223733745284, 8308.169183081643, 8389.370797190357, 8398.790030249293, 8569.442986205877, 10398.68803935883, 12814.295213763333, 12825.23048617086, 13105.0, 13232.801507977196, 9140.497272650067, 11189.88810111921, 11402.714932126763, 11416.200865939305, 11428.82856757166, 10979.451027448436, 11042.0, 11086.667399978014, 11290.604828830952, 11391.316521008435, 10709.839352409565, 11035.668929932115, 11409.771804563696, 11642.53429862796, 11647.883521165018, 7019.227884932551, 7100.147982242164, 7349.9342595322305, 7444.181140074479, 8514.10601886793, 6396.9040464394, 6800.387965082969, 7818.452708310364, 8138.959305203436, 9271.536423178948], "value": 9769.964871270104, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1424.843267240604, 1451.9564413067599, 1462.736707392669, 1467.40570069122, 1484.0798704803021, 7490.88763668544, 7834.686612535506, 8066.757997260084, 8469.57652117394, 8505.0, 7986.0, 8217.79455513611, 8237.640789269779, 8360.163983601637, 8361.289290410323, 2026.2806703620224, 2276.2602154299793, 2322.396176993985, 2327.325075728037, 2332.1137967572386, 1920.7695076590758, 2288.141946769961, 2328.895199716017, 2340.063974410241, 2344.554534638422, 1919.3186418821326, 2289.3722672862136, 2293.094227780034, 2308.7575804540534, 2310.90756369745, 2231.2079211879714, 2261.412032871449, 2276.738175109867, 2301.631738921781, 2301.873396963175, 2272.9545409091725, 2309.8614083155067, 2334.48641298914, 2342.9648555271683, 2365.0657990093987, 8339.45793523419, 8379.83240335196, 8438.2827459666, 8449.873251901226, 8450.154984501562, 4428.778561071934, 5069.188929771219, 5174.327337446108, 5207.453217412193, 5210.270562121306, 3282.2614911644687, 3354.530365748797, 3498.6326435724136, 3549.5563054618174, 3550.9644903550893, 1190.0598527163531, 1202.903767698582, 1214.2021845686827, 1216.738401243737, 1234.969125771863, 9103.226225770817, 10694.0, 10781.676549703521, 10782.946085269601, 10815.134789216923, 4412.602865742098, 4594.19601569724, 4604.033153037833, 4640.396748422683, 4692.436907571045, 9470.105978804268, 9490.098440648128, 9517.143457088896, 9527.047295270417, 9528.761780955532, 5233.921491177635, 5238.476152384807, 5241.187615919539, 5242.266082748418, 5244.056069907377, 4172.472866939344, 4603.861884143481, 4675.719456832533, 4773.689710168828, 4788.4253889533475, 3763.8118094095184, 3801.4487899254455, 3863.690904727615, 3867.207222519377, 3884.8446062157195, 1261.6278197931633, 1262.1732765038914, 1267.233323839064, 1277.7316763479587, 1278.5105352264507, 927.4249965021729, 933.2067742419025, 939.5349302095507, 940.3464592108555, 972.0668158567854, 5607.495325420732, 5714.142878568165, 5820.330661973842, 5911.940880591139, 5929.584929055011, 568.7469076261024, 572.130361849994, 576.702997956053, 580.8170426315697, 591.3701907468533, 3864.632859878307, 3867.4972253607416, 3955.960440395617, 3956.6834653227675, 3972.324704800191, 7689.846523021592, 8602.440841345418, 9738.513074346254, 10014.198864090855, 10053.04496072872, 9873.456959867277, 9994.950025249973, 10016.899831001596, 10027.39835609851, 10037.447940363205, 7388.224236455309, 7798.22017798227, 7900.735882258926, 7907.288344049063, 7954.522728636184, 5413.485718856702, 5430.104032834538, 5903.114532820111, 5954.255718035246, 6002.189704389852, 9023.233025192669, 9168.679096231599, 9224.985251622451, 9235.307351948497, 9243.260539156718, 8880.644774208959, 9053.773655658399, 9166.129217724305, 9222.44665320083, 9377.343585948918, 6514.967425162844, 6719.764808231688, 6906.792796216121, 6958.0, 7150.785476435715, 7758.68965241384, 7910.248526389983, 7937.563434011185, 7952.085510166405, 7955.1249362568915, 6228.626282423069, 7040.401565866959, 7145.39264162552, 7197.244289349753, 7273.0, 6183.288921773874, 7011.474139439664, 7023.0, 7039.0, 7047.47143964215, 6259.280182779039, 6341.4292713656, 6407.967960160169, 6411.51913606472, 6412.839679008062, 4394.98915249488, 4953.628477864103, 4980.825671101494, 4991.850244492671, 5044.419891712353, 4932.235503496964, 4959.528844759742, 5013.24801279811, 5086.694798312114, 5091.516305950929], "value": 4346.922042105966, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.70323895538195, 316.87008326586107, 318.71240189633886, 357.32465639940534, 358.49631268068373, 4903.0, 5058.0, 5342.866428339294, 5392.137258038714, 5498.477644623765, 5049.0, 5365.839024829252, 5512.574467234424, 5525.281713377255, 5540.667559946406, 3538.203904121565, 4318.56814318568, 4354.063876266602, 4354.586314300136, 4392.573792441901, 3902.6487616114546, 4119.303993447472, 4251.979524914013, 4325.372820940962, 4328.026194106316, 4062.878113656595, 4215.220184265921, 4263.934016495876, 4280.764557949312, 4292.098659281539, 4238.87283381499, 4262.190183865071, 4273.807678654468, 4317.409554267446, 4328.156009578133, 3647.3981793004386, 3812.856143157042, 3822.57951625321, 3827.4978394768755, 3842.5773164951834, 4484.663650226246, 4582.427196600425, 5114.539691427753, 5360.946390536123, 5361.624686271963, 4985.35190425246, 4997.350344455234, 4998.425181104183, 5008.499150084999, 5036.219385995177, 4872.3909511311085, 5705.62913410627, 5716.942830571683, 5754.884902301972, 5886.764529418818, 4174.853880114181, 4182.351735481006, 4215.810288537024, 4217.502786510775, 4219.43037689914, 5214.957008598296, 5568.515012712475, 5582.581306401996, 5997.010493268605, 6143.385661433866, 3957.604239576077, 4233.386159006926, 4254.829921771538, 4283.528811830696, 4301.5698430156735, 3515.57813062429, 4239.97880010598, 4312.37470566766, 4347.2176407672705, 4350.347447882779, 4444.577765112309, 4507.977460112678, 4573.667429610922, 4960.956820191533, 5007.824726134568, 4766.4995175506765, 5265.947340526623, 5445.74001789253, 5480.725963701799, 5902.173695682608, 1317.914335568185, 1356.9185848849102, 1408.5703860322494, 1422.9501967431297, 1446.7251222267735, 930.8178613161383, 989.44096585429, 992.4666390426808, 1004.5830980143305, 1008.6383382433802, 4542.954570454319, 4671.995520963009, 5173.40505841833, 5268.970342053335, 5348.946510534923, 4995.225740010232, 5031.7987280509105, 5248.921266181011, 5746.856328591819, 5748.626339287933, 4772.284157376352, 4800.5598320504405, 5216.400113986864, 5237.240600113038, 5237.371515418175, 2627.408833012557, 2737.75504086647, 2858.1425572328217, 3002.219422950005, 3035.8937437189593, 5149.579697542149, 5523.7790488380815, 5646.774129034875, 6012.549058820606, 6039.577229593974, 5591.769810641652, 5597.216389705444, 5911.512207797334, 5957.761689532457, 5977.312609049928, 5260.395054568774, 6024.518038556905, 6026.879462410728, 6047.365026672224, 6050.5764596479585, 5084.737291905067, 5183.740812959261, 5664.207011018543, 5765.4522820332, 5799.94200057994, 5280.81517146898, 5325.520703136582, 5970.343262241063, 5987.700614969147, 5994.670293133745, 5113.795448182031, 5432.266644003009, 5483.177523371526, 5497.340319161726, 6319.778807741707, 3141.246100935805, 3899.53205615328, 3946.934327731554, 3996.960030399659, 4004.6596039336982, 4792.976035119802, 4813.374261346003, 4962.404511458648, 6108.541859360655, 6155.107509411021, 5100.69395836251, 5202.583793296603, 5790.449907258803, 5844.649321040752, 5912.645241285537, 4978.004399120263, 5696.686682232517, 5908.4047307227665, 5922.496587790086, 5935.655416110699, 4672.32251323563, 5503.394626591152, 5706.9714651426475, 5745.2818397700285, 5796.594238403271, 5917.142014407801, 5922.881542369042, 5925.703714814156, 5930.915923719728, 5932.550587059705, 5546.778128874961, 5726.226959360433, 5760.855978600569, 5784.566157538286, 5794.101914203306], "value": 4232.374665190512, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.8486725354048, 708.6598432752279, 755.312665474418, 828.7886588919824, 864.3430992445741, 14682.192479413628, 14909.850901491009, 15266.236688165574, 15385.615359616015, 15446.150461724601, 14347.928260358683, 14359.64100897478, 14434.783478247835, 14507.347169377377, 14540.200288984102, 871.8822958900531, 874.073482108965, 880.6653471680752, 883.3551507399602, 884.4383816276678, 683.5830143612393, 704.3519961635308, 848.5545088828384, 861.452977359375, 864.5849992003842, 926.0045451140052, 926.5413620257948, 926.9212116970066, 932.5523748600676, 938.5682586010444, 918.4672889723944, 921.4885738415144, 924.4129977464358, 925.1719710858778, 928.9303302252358, 681.3561184680478, 693.6081114170478, 696.8989496523027, 701.9754308599225, 702.7715992302471, 13286.26925519096, 13664.248466334348, 15133.864960128034, 15162.924185379114, 15405.537833865003, 14238.359273832702, 14337.0, 14603.70792584153, 14649.85028218938, 14683.0, 11602.322451189291, 11888.940555297255, 11899.702507437296, 11926.344051077187, 11968.940155299168, 3137.670544592808, 3584.6953008994265, 3674.7427680062406, 3807.904802379935, 3848.1149335652676, 101807.0, 103759.0, 104311.47844260806, 105494.0, 105792.47103764508, 10812.621558245424, 11031.393273370042, 11182.944085279521, 11212.0, 11658.94170529142, 18905.7164142538, 20264.696029559567, 22398.544094633795, 23233.419164520685, 23245.7675423247, 7654.655540500691, 7704.345130663844, 7734.574598397142, 7792.103908050533, 7897.289243967955, 4210.663146948298, 4234.703570750018, 4301.0, 4349.934750978738, 4399.758013309299, 3337.482690182977, 3435.3988052091286, 3541.061618671054, 3638.308721342936, 3645.5989841117457, 630.6815058395573, 645.0356716708551, 647.439964430764, 648.3775575447623, 663.1478550063242, 671.1542268857671, 703.8064532253671, 703.8697840899348, 712.8111050571603, 714.3071220915704, 10261.486925653688, 10261.589536418609, 10362.948185259025, 10634.255602107933, 10689.83965240522, 656.2715385921623, 669.136813510566, 678.4199509419474, 690.3823962436895, 691.405391363427, 4620.4686461056735, 4935.136351138536, 4938.160512712848, 4940.530649588283, 4946.727929963887, 101363.49318253361, 113069.43465282621, 113801.0, 114374.42812785883, 119805.40097299458, 96853.0, 98475.0, 98792.5060374708, 99789.50105249428, 100680.49659751654, 73999.0, 88363.0, 88951.55524222337, 90576.547117264, 91482.0, 9371.390859594105, 9608.13526782565, 9618.711438656852, 9619.807603848023, 9652.806943861224, 50938.74530627323, 54426.0, 56003.43996560145, 56902.0, 57122.42877571337, 61855.76288474295, 62975.68512157593, 66489.33510664832, 69333.65333173504, 73828.89256661262, 13029.348532573144, 13078.934605326913, 13323.733525329633, 13399.263040532864, 13866.584002479942, 14309.356078976263, 14499.420023199376, 14696.0, 14833.703325933637, 15465.60809527148, 13550.322483875963, 13610.795838062635, 14002.929985350009, 14212.644683882985, 14296.0, 11479.827802583135, 13587.599570282186, 13648.795268071186, 13675.521356752466, 13775.586732398044, 13364.799528006894, 13494.865051349361, 13527.932360338134, 13717.725645486835, 14063.347245782721, 10779.461026948464, 10813.62152324665, 11121.555137794398, 11126.888731112585, 11280.717982050186, 9380.390274632127, 9649.324547281904, 10589.682309530726, 10863.782724345627, 10865.619703310345], "value": 7620.316485348363, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1556.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 173.0, 110.0, 173.0, 173.0, 164.0, 120.0, 117.0, 119.0, 119.0], "value": 137.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 111.0, 111.0, 166.0, 139.0, 112.0, 111.0, 114.0, 131.0, 112.0], "value": 125.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 204.0, 204.0, 219.0, 195.0, 198.0, 226.0, 260.0, 200.0, 194.0], "value": 209.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 58.0, 83.0, 60.0, 60.0, 60.0, 60.0, 71.0, 62.0], "value": 65.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 85.0, 73.0, 70.0, 70.0, 81.0, 65.0, 70.0, 82.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 76.0, 118.0, 131.0, 91.0, 113.0, 108.0, 120.0, 136.0, 112.0], "value": 109.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 96.0, 99.0, 98.0, 96.0, 97.0, 95.0, 95.0, 98.0, 97.0], "value": 96.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 143.0, 91.0, 97.0, 92.0, 94.0, 95.0, 92.0, 110.0, 92.0], "value": 105.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 61.0, 64.0, 63.0, 64.0, 62.0, 63.0, 63.0], "value": 62.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 76.0, 83.0, 79.0, 70.0, 70.0, 70.0, 72.0, 72.0, 73.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 92.0, 92.0, 92.0, 90.0, 90.0, 91.0, 89.0, 91.0, 88.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 146.0, 134.0, 141.0, 141.0, 141.0, 139.0, 140.0, 188.0, 142.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 174.0, 170.0, 171.0, 172.0, 174.0, 175.0, 211.0, 206.0, 215.0], "value": 184.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 67.0, 69.0, 83.0, 91.0, 88.0, 75.0, 68.0, 68.0], "value": 74.9, "unit": "score"}]}]} 08:43:28 INFO - Return code: 0 08:43:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:43:28 ERROR - # TBPL SUCCESS # 08:43:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:43:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:43:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:43:28 INFO - 2017-01-11 08:43:28.108 screenresolution[1661:11056] starting screenresolution argv=screenresolution get 08:43:28 INFO - 2017-01-11 08:43:28.111 screenresolution[1661:11056] Display 0: 1600x1200x32@60 08:43:28 INFO - 2017-01-11 08:43:28.117 screenresolution[1662:11059] starting screenresolution argv=screenresolution list 08:43:28 INFO - Available Modes on Display 0 08:43:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:43:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:43:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:43:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:43:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:43:28 INFO - Intel Iris: 08:43:28 INFO - Chipset Model: Intel Iris 08:43:28 INFO - Type: GPU 08:43:28 INFO - Bus: Built-In 08:43:28 INFO - VRAM (Dynamic, Max): 1536 MB 08:43:28 INFO - Vendor: Intel (0x8086) 08:43:28 INFO - Device ID: 0x0a2e 08:43:28 INFO - Revision ID: 0x0009 08:43:28 INFO - Displays: 08:43:28 INFO - Display: 08:43:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:43:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:43:28 INFO - Display Serial Number: 1600x1200 60 08:43:28 INFO - Main Display: Yes 08:43:28 INFO - Mirror: Off 08:43:28 INFO - Online: Yes 08:43:28 INFO - Rotation: Supported 08:43:28 INFO - Return code: 0 08:43:28 INFO - Running post-action listener: _resource_record_post_action 08:43:28 INFO - [mozharness: 2017-01-11 16:43:28.353175Z] Finished run-tests step (success) 08:43:28 INFO - Running post-run listener: _resource_record_post_run 08:43:28 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 39126528; Write bytes: 555286528; Read time: 379; Write time: 1787 08:43:28 INFO - TinderboxPrint: CPU usage
54.4% 08:43:28 INFO - TinderboxPrint: I/O read bytes / time
39,126,528 / 379 08:43:28 INFO - TinderboxPrint: I/O write bytes / time
555,286,528 / 1,787 08:43:28 INFO - TinderboxPrint: CPU idle
2,186.2 (45.6%) 08:43:28 INFO - TinderboxPrint: CPU system
963.9 (20.1%) 08:43:28 INFO - TinderboxPrint: CPU user
1,643.5 (34.3%) 08:43:28 INFO - TinderboxPrint: Swap in / out
290,209,792 / 0 08:43:28 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 251144704; Write bytes: 250863616; Read time: 18218; Write time: 471 08:43:28 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 34604544; Write bytes: 300959744; Read time: 346; Write time: 1271 08:43:28 INFO - Running post-run listener: _upload_blobber_files 08:43:28 INFO - Blob upload gear active. 08:43:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:43:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:43:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:43:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:43:29 INFO - (blobuploader) - INFO - Open directory for files ... 08:43:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:43:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:43:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:43:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:43:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:43:30 INFO - (blobuploader) - INFO - Done attempting. 08:43:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:43:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:43:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:43:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:43:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:43:31 INFO - (blobuploader) - INFO - Done attempting. 08:43:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:43:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:43:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:43:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:43:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:43:32 INFO - (blobuploader) - INFO - Done attempting. 08:43:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:43:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:43:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:43:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:43:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:43:34 INFO - (blobuploader) - INFO - Done attempting. 08:43:34 INFO - (blobuploader) - INFO - Iteration through files over. 08:43:34 INFO - Return code: 0 08:43:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:43:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:43:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aabeef219466803538cdac01de7a172378274bd09f64fe4ec0b729e2d08ad93bc33c512ef29345b9ff1d3530a2c291c9bd283b832419593216ce489dc6b8561c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb2f4036a985ae5580709121d61fe6bc717f58dec20851842944c07de7274d80be0e644c25df41fc4fdfe884e99269d76e91943c8bfea5ed2d9a2f7bcaaf4fa2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b911ec88827d01a434a97eb9798b259ec2f2e51238f0a04579d808412f47c7c7829d5a7a32a9e798eabdba8d6aad2c8179b674be5c5ccfd030f4a9497e028302", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbc1870272884187c766ae60b65128ad214119db1d4b9607215e80b1daf0e904ae5750031a94e0a3d70e13d216fe1e24b6be98885cd0dda9c2596129b4a1e51a"} 08:43:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:43:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:43:34 INFO - Contents: 08:43:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aabeef219466803538cdac01de7a172378274bd09f64fe4ec0b729e2d08ad93bc33c512ef29345b9ff1d3530a2c291c9bd283b832419593216ce489dc6b8561c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb2f4036a985ae5580709121d61fe6bc717f58dec20851842944c07de7274d80be0e644c25df41fc4fdfe884e99269d76e91943c8bfea5ed2d9a2f7bcaaf4fa2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b911ec88827d01a434a97eb9798b259ec2f2e51238f0a04579d808412f47c7c7829d5a7a32a9e798eabdba8d6aad2c8179b674be5c5ccfd030f4a9497e028302", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbc1870272884187c766ae60b65128ad214119db1d4b9607215e80b1daf0e904ae5750031a94e0a3d70e13d216fe1e24b6be98885cd0dda9c2596129b4a1e51a"} 08:43:34 INFO - Running post-run listener: copy_logs_to_upload_dir 08:43:34 INFO - Copying logs to upload dir... 08:43:34 INFO - mkdir: /builds/slave/test/build/upload/logs 08:43:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.682882 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-11 08:43:34.497310) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 08:43:34.500922) ========= 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.1I3h6dsc0X/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.zufEuHs7Mp/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aabeef219466803538cdac01de7a172378274bd09f64fe4ec0b729e2d08ad93bc33c512ef29345b9ff1d3530a2c291c9bd283b832419593216ce489dc6b8561c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb2f4036a985ae5580709121d61fe6bc717f58dec20851842944c07de7274d80be0e644c25df41fc4fdfe884e99269d76e91943c8bfea5ed2d9a2f7bcaaf4fa2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b911ec88827d01a434a97eb9798b259ec2f2e51238f0a04579d808412f47c7c7829d5a7a32a9e798eabdba8d6aad2c8179b674be5c5ccfd030f4a9497e028302", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbc1870272884187c766ae60b65128ad214119db1d4b9607215e80b1daf0e904ae5750031a94e0a3d70e13d216fe1e24b6be98885cd0dda9c2596129b4a1e51a"} build_url:https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011010 build_url: 'https://queue.taskcluster.net/v1/task/b3sLrzrETBW_9pSYUuP5yQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aabeef219466803538cdac01de7a172378274bd09f64fe4ec0b729e2d08ad93bc33c512ef29345b9ff1d3530a2c291c9bd283b832419593216ce489dc6b8561c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb2f4036a985ae5580709121d61fe6bc717f58dec20851842944c07de7274d80be0e644c25df41fc4fdfe884e99269d76e91943c8bfea5ed2d9a2f7bcaaf4fa2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b911ec88827d01a434a97eb9798b259ec2f2e51238f0a04579d808412f47c7c7829d5a7a32a9e798eabdba8d6aad2c8179b674be5c5ccfd030f4a9497e028302", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbc1870272884187c766ae60b65128ad214119db1d4b9607215e80b1daf0e904ae5750031a94e0a3d70e13d216fe1e24b6be98885cd0dda9c2596129b4a1e51a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 08:43:34.536809) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 08:43:34.537226) ========= 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.1I3h6dsc0X/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.zufEuHs7Mp/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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.005158 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 08:43:34.586920) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 08:43:34.587295) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 08:43:34.587691) ========= ========= Total master_lag: 0.20 =========