builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0079 starttime: 1483215636.62 results: success (0) buildid: 20161231105319 builduid: 5bcf72985f5d432188be2f5d7752d674 revision: ff4defb6f945e9bfc22ac4b465b3f3d88567da07 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-31 12:20:36.619454) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-31 12:20:36.619845) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-31 12:20:36.620124) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-31 12:20:36.665246) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 12:20:36.665562) ========= 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.jaLxXKW56L/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.zwPx9kZVhX/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-31 12:20:36-- 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% 14.9M=0.001s 2016-12-31 12:20:37 (14.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.543431 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 12:20:37.233008) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 12:20:37.236311) ========= 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.jaLxXKW56L/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.zwPx9kZVhX/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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.026554 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 12:20:37.284884) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 12:20:37.288236) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ff4defb6f945e9bfc22ac4b465b3f3d88567da07 --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 ff4defb6f945e9bfc22ac4b465b3f3d88567da07 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jaLxXKW56L/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.zwPx9kZVhX/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-31 12:20:37,382 truncating revision to first 12 chars 2016-12-31 12:20:37,382 Setting DEBUG logging. 2016-12-31 12:20:37,382 attempt 1/10 2016-12-31 12:20:37,382 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ff4defb6f945?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-31 12:20:37,710 unpacking tar archive at: mozilla-central-ff4defb6f945/testing/mozharness/ program finished with exit code 0 elapsedTime=0.589655 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 12:20:37.904423) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-31 12:20:37.904773) ========= script_repo_revision: ff4defb6f945e9bfc22ac4b465b3f3d88567da07 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-31 12:20:37.905164) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-31 12:20:37.905452) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-31 12:20:37.925399) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-31 12:20:37.925684) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jaLxXKW56L/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.zwPx9kZVhX/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 12:20:38 INFO - MultiFileLogger online at 20161231 12:20:38 in /builds/slave/test 12:20:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:20:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:20:38 INFO - {'append_to_log': False, 12:20:38 INFO - 'base_work_dir': '/builds/slave/test', 12:20:38 INFO - 'blob_upload_branch': 'Firefox', 12:20:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:20:38 INFO - 'branch': 'Firefox', 12:20:38 INFO - 'buildbot_json_path': 'buildprops.json', 12:20:38 INFO - 'config_files': ('talos/mac_config.py',), 12:20:38 INFO - 'default_actions': ('clobber', 12:20:38 INFO - 'read-buildbot-config', 12:20:38 INFO - 'download-and-extract', 12:20:38 INFO - 'populate-webroot', 12:20:38 INFO - 'create-virtualenv', 12:20:38 INFO - 'install', 12:20:38 INFO - 'run-tests'), 12:20:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:20:38 INFO - 'download_minidump_stackwalk': True, 12:20:38 INFO - 'download_symbols': 'ondemand', 12:20:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:20:38 INFO - 'tooltool.py': '/tools/tooltool.py', 12:20:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:20:38 INFO - '/tools/misc-python/virtualenv.py')}, 12:20:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:20:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:20:38 INFO - 'installer_path': 'installer.exe', 12:20:38 INFO - 'log_level': 'info', 12:20:38 INFO - 'log_name': 'talos', 12:20:38 INFO - 'log_to_console': True, 12:20:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:20:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:20:38 INFO - 'opt_config_files': (), 12:20:38 INFO - 'pip_index': False, 12:20:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:20:38 INFO - 'cmd': ('bash', 12:20:38 INFO - '-c', 12:20:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:20:38 INFO - 'enabled': True, 12:20:38 INFO - 'halt_on_failure': False, 12:20:38 INFO - 'name': 'check_screen_resolution'},), 12:20:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:20:38 INFO - 'cmd': ('bash', 12:20:38 INFO - '-c', 12:20:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:20:38 INFO - 'enabled': True, 12:20:38 INFO - 'halt_on_failure': False, 12:20:38 INFO - 'name': 'check_screen_resolution'},), 12:20:38 INFO - 'run_cmd_checks_enabled': True, 12:20:38 INFO - 'sps_profile': False, 12:20:38 INFO - 'sps_profile_interval': 0, 12:20:38 INFO - 'suite': 'dromaeojs', 12:20:38 INFO - 'system_bits': '32', 12:20:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:20:38 INFO - 'title': 't-yosemite-r7-0079', 12:20:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:20:38 INFO - 'use_talos_json': True, 12:20:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:20:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:20:38 INFO - 'work_dir': 'build'} 12:20:38 INFO - [mozharness: 2016-12-31 20:20:38.131401Z] Running clobber step. 12:20:38 INFO - Running pre-action listener: _resource_record_pre_action 12:20:38 INFO - Running main action method: clobber 12:20:38 INFO - rmtree: /builds/slave/test/build 12:20:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:20:40 INFO - Running post-action listener: _resource_record_post_action 12:20:40 INFO - [mozharness: 2016-12-31 20:20:40.503175Z] Finished clobber step (success) 12:20:40 INFO - [mozharness: 2016-12-31 20:20:40.503433Z] Running read-buildbot-config step. 12:20:40 INFO - Running pre-action listener: _resource_record_pre_action 12:20:40 INFO - Running main action method: read_buildbot_config 12:20:40 INFO - Using buildbot properties: 12:20:40 INFO - { 12:20:40 INFO - "project": "", 12:20:40 INFO - "product": "firefox", 12:20:40 INFO - "script_repo_revision": "production", 12:20:40 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 12:20:40 INFO - "repository": "", 12:20:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 12:20:40 INFO - "buildid": "20161231105319", 12:20:40 INFO - "pgo_build": "False", 12:20:40 INFO - "basedir": "/builds/slave/test", 12:20:40 INFO - "buildnumber": 47, 12:20:40 INFO - "slavename": "t-yosemite-r7-0079", 12:20:40 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:20:40 INFO - "platform": "yosemite_r7", 12:20:40 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:20:40 INFO - "branch": "mozilla-central", 12:20:40 INFO - "repo_path": "mozilla-central", 12:20:40 INFO - "revision": "ff4defb6f945e9bfc22ac4b465b3f3d88567da07", 12:20:40 INFO - "stage_platform": "macosx64", 12:20:40 INFO - "builduid": "5bcf72985f5d432188be2f5d7752d674", 12:20:40 INFO - "slavebuilddir": "test" 12:20:40 INFO - } 12:20:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 12:20:40 INFO - Running post-action listener: _resource_record_post_action 12:20:40 INFO - [mozharness: 2016-12-31 20:20:40.509258Z] Finished read-buildbot-config step (success) 12:20:40 INFO - [mozharness: 2016-12-31 20:20:40.509571Z] Running download-and-extract step. 12:20:40 INFO - Running pre-action listener: _resource_record_pre_action 12:20:40 INFO - Running main action method: download_and_extract 12:20:40 INFO - mkdir: /builds/slave/test/build/tests 12:20:40 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')]} 12:20:40 INFO - trying https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 12:20:40 INFO - Downloading https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/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 12:20:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/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 12:20:41 INFO - Downloaded 1199 bytes. 12:20:41 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 12:20:41 INFO - Using the following test package requirements: 12:20:41 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:20:41 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:20:41 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 12:20:41 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:20:41 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 12:20:41 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:20:41 INFO - u'jsshell-mac.zip'], 12:20:41 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:20:41 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 12:20:41 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:20:41 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:20:41 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 12:20:41 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:20:41 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 12:20:41 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:20:41 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 12:20:41 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:20:41 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 12:20:41 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:20:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:20:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:20:41 INFO - Fetch https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:20:42 INFO - Expected file size: 16299770 12:20:42 INFO - Obtained file size: 16299770 12:20:49 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 12:20:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:20:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:20:49 INFO - Fetch https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:20:50 INFO - Expected file size: 16299770 12:20:50 INFO - Obtained file size: 16299770 12:20:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 12:20:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:20:56 INFO - Fetch https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 12:20:58 INFO - Expected file size: 14409776 12:20:58 INFO - Obtained file size: 14409776 12:20:58 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')]} 12:20:58 INFO - trying https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:20:58 INFO - Downloading https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 12:20:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/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 12:21:00 INFO - Downloaded 69762639 bytes. 12:21:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:21:00 INFO - mkdir: /builds/slave/test/properties 12:21:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:21:00 INFO - Writing to file /builds/slave/test/properties/build_url 12:21:00 INFO - Contents: 12:21:00 INFO - build_url:https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:21:01 INFO - Running post-action listener: _resource_record_post_action 12:21:01 INFO - Running post-action listener: set_extra_try_arguments 12:21:01 INFO - [mozharness: 2016-12-31 20:21:01.004742Z] Finished download-and-extract step (success) 12:21:01 INFO - [mozharness: 2016-12-31 20:21:01.004864Z] Running populate-webroot step. 12:21:01 INFO - Running pre-action listener: _resource_record_pre_action 12:21:01 INFO - Running main action method: populate_webroot 12:21:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:21:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:21:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:21:01 INFO - '--tppagecycles', 12:21:01 INFO - '7'], 12:21:01 INFO - 'tests': ['tsvgm']}}, 12:21:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:21:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:21:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:21:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:21:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:21:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:21:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:21:01 INFO - 'talos_options': ['--disable-e10s'], 12:21:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:21:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:21:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:21:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:21:01 INFO - 'talos_options': ['--disable-e10s'], 12:21:01 INFO - 'tests': ['damp', 'tps']}, 12:21:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:21:01 INFO - 'tests': ['damp', 'tps']}, 12:21:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:21:01 INFO - 'tests': ['dromaeo_dom']}, 12:21:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:21:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:21:01 INFO - 'tests': ['basic_compositor_video']}, 12:21:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:21:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:21:01 INFO - 'tests': ['a11yr', 12:21:01 INFO - 'ts_paint', 12:21:01 INFO - 'tpaint', 12:21:01 INFO - 'sessionrestore', 12:21:01 INFO - 'sessionrestore_no_auto_restore', 12:21:01 INFO - 'tabpaint']}, 12:21:01 INFO - 'other-e10s': {'tests': ['a11yr', 12:21:01 INFO - 'ts_paint', 12:21:01 INFO - 'tpaint', 12:21:01 INFO - 'sessionrestore', 12:21:01 INFO - 'sessionrestore_no_auto_restore', 12:21:01 INFO - 'tabpaint']}, 12:21:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:21:01 INFO - 'ts_paint', 12:21:01 INFO - 'tpaint', 12:21:01 INFO - 'sessionrestore', 12:21:01 INFO - 'sessionrestore_no_auto_restore', 12:21:01 INFO - 'tabpaint']}, 12:21:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:21:01 INFO - 'ts_paint', 12:21:01 INFO - 'tpaint', 12:21:01 INFO - 'sessionrestore', 12:21:01 INFO - 'sessionrestore_no_auto_restore', 12:21:01 INFO - 'tabpaint']}, 12:21:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:21:01 INFO - 'tests': ['a11yr', 12:21:01 INFO - 'ts_paint', 12:21:01 INFO - 'tpaint', 12:21:01 INFO - 'sessionrestore', 12:21:01 INFO - 'sessionrestore_no_auto_restore', 12:21:01 INFO - 'tabpaint']}, 12:21:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:21:01 INFO - 'tests': ['a11yr', 12:21:01 INFO - 'ts_paint', 12:21:01 INFO - 'tpaint', 12:21:01 INFO - 'sessionrestore', 12:21:01 INFO - 'sessionrestore_no_auto_restore', 12:21:01 INFO - 'tabpaint']}, 12:21:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:21:01 INFO - 'tests': ['tsvgx', 12:21:01 INFO - 'tsvgr_opacity', 12:21:01 INFO - 'tart', 12:21:01 INFO - 'tscrollx', 12:21:01 INFO - 'cart', 12:21:01 INFO - 'tsvg_static']}, 12:21:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:21:01 INFO - 'tsvgr_opacity', 12:21:01 INFO - 'tart', 12:21:01 INFO - 'tscrollx', 12:21:01 INFO - 'cart', 12:21:01 INFO - 'tsvg_static']}, 12:21:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:21:01 INFO - 'talos_options': ['--disable-e10s'], 12:21:01 INFO - 'tests': ['tp5o']}, 12:21:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:21:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:21:01 INFO - 'talos_options': ['--disable-e10s', 12:21:01 INFO - '--xperf_path', 12:21:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:21:01 INFO - 'tests': ['tp5n']}, 12:21:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:21:01 INFO - 'talos_options': ['--xperf_path', 12:21:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:21:01 INFO - 'tests': ['tp5n']}}, 12:21:01 INFO - 'talos.zip': {'path': '', 12:21:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:21:01 INFO - Running post-action listener: _resource_record_post_action 12:21:01 INFO - [mozharness: 2016-12-31 20:21:01.023208Z] Finished populate-webroot step (success) 12:21:01 INFO - [mozharness: 2016-12-31 20:21:01.023344Z] Running create-virtualenv step. 12:21:01 INFO - Running pre-action listener: _resource_record_pre_action 12:21:01 INFO - Running main action method: create_virtualenv 12:21:01 INFO - Creating virtualenv /builds/slave/test/build/venv 12:21:01 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 12:21:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:21:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:21:01 INFO - Using real prefix '/tools/python27' 12:21:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:21:01 INFO - Installing distribute.............................................................................................................................................................................................done. 12:21:04 INFO - Installing pip.................done. 12:21:04 INFO - Return code: 0 12:21:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:21:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:21:04 INFO - Reading from file tmpfile_stdout 12:21:04 INFO - Output received: 12:21:04 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) 12:21:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:21: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')]} 12:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21: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')]} 12:21:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:04 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 0x10629ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6d14a4900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106078d30>, '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 0x105f9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10606dbe8>, '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.jaLxXKW56L/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.zwPx9kZVhX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 12:21:04 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 12:21:04 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 12:21:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jaLxXKW56L/Render', 12:21:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:04 INFO - 'HOME': '/Users/cltbld', 12:21:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:04 INFO - 'LOGNAME': 'cltbld', 12:21:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:04 INFO - 'MOZ_NO_REMOTE': '1', 12:21:04 INFO - 'NO_EM_RESTART': '1', 12:21:04 INFO - 'PAGER': '/bin/cat', 12:21:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:04 INFO - 'PWD': '/builds/slave/test', 12:21:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:04 INFO - 'SHELL': '/bin/bash', 12:21:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zwPx9kZVhX/Listeners', 12:21:04 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 12:21:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:04 INFO - 'USER': 'cltbld', 12:21:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:04 INFO - 'XPC_FLAGS': '0x0', 12:21:04 INFO - 'XPC_SERVICE_NAME': '0', 12:21:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:05 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 12:21:05 INFO - Cleaning up... 12:21:05 INFO - Return code: 0 12:21:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:21: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')]} 12:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21: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')]} 12:21:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21: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', '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 0x10629ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6d14a4900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106078d30>, '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 0x105f9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10606dbe8>, '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.jaLxXKW56L/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.zwPx9kZVhX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 12:21: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', 'psutil>=3.1.1'] in /builds/slave/test/build 12:21: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 psutil>=3.1.1 12:21:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jaLxXKW56L/Render', 12:21:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:05 INFO - 'HOME': '/Users/cltbld', 12:21:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:05 INFO - 'LOGNAME': 'cltbld', 12:21:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:05 INFO - 'MOZ_NO_REMOTE': '1', 12:21:05 INFO - 'NO_EM_RESTART': '1', 12:21:05 INFO - 'PAGER': '/bin/cat', 12:21:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:05 INFO - 'PWD': '/builds/slave/test', 12:21:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:05 INFO - 'SHELL': '/bin/bash', 12:21:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zwPx9kZVhX/Listeners', 12:21:05 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 12:21:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:05 INFO - 'USER': 'cltbld', 12:21:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:05 INFO - 'XPC_FLAGS': '0x0', 12:21:05 INFO - 'XPC_SERVICE_NAME': '0', 12:21:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:05 INFO - Downloading/unpacking psutil>=3.1.1 12:21: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 12:21: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 12:21: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 12:21: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 12:21: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 12:21: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 12:21:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:21:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:21:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:21:09 INFO - Installing collected packages: psutil 12:21:09 INFO - Running setup.py install for psutil 12:21:09 INFO - building 'psutil._psutil_osx' extension 12:21:09 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 12:21:10 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 12:21:10 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 12:21:10 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 12:21:10 INFO - building 'psutil._psutil_posix' extension 12:21:10 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 12:21:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:21:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:21:10 INFO - ^ 12:21:10 INFO - 1 warning generated. 12:21:10 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 12:21:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:21:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:21:10 INFO - Successfully installed psutil 12:21:10 INFO - Cleaning up... 12:21:10 INFO - Return code: 0 12:21:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10629ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6d14a4900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106078d30>, '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 0x105f9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10606dbe8>, '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.jaLxXKW56L/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.zwPx9kZVhX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 12:21:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:21:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:21:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jaLxXKW56L/Render', 12:21:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:10 INFO - 'HOME': '/Users/cltbld', 12:21:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:10 INFO - 'LOGNAME': 'cltbld', 12:21:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:10 INFO - 'MOZ_NO_REMOTE': '1', 12:21:10 INFO - 'NO_EM_RESTART': '1', 12:21:10 INFO - 'PAGER': '/bin/cat', 12:21:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:10 INFO - 'PWD': '/builds/slave/test', 12:21:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:10 INFO - 'SHELL': '/bin/bash', 12:21:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zwPx9kZVhX/Listeners', 12:21:10 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 12:21:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:10 INFO - 'USER': 'cltbld', 12:21:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:10 INFO - 'XPC_FLAGS': '0x0', 12:21:10 INFO - 'XPC_SERVICE_NAME': '0', 12:21:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:21:10 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 12:21:10 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 12:21:10 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 12:21:10 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 12:21:10 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 12:21:10 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 12:21:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:21:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:21:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:21:13 INFO - Installing collected packages: mozsystemmonitor 12:21:13 INFO - Running setup.py install for mozsystemmonitor 12:21:13 INFO - Successfully installed mozsystemmonitor 12:21:13 INFO - Cleaning up... 12:21:13 INFO - Return code: 0 12:21:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10629ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6d14a4900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106078d30>, '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 0x105f9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10606dbe8>, '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.jaLxXKW56L/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.zwPx9kZVhX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 12:21:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:21:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jaLxXKW56L/Render', 12:21:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:13 INFO - 'HOME': '/Users/cltbld', 12:21:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:13 INFO - 'LOGNAME': 'cltbld', 12:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:13 INFO - 'MOZ_NO_REMOTE': '1', 12:21:13 INFO - 'NO_EM_RESTART': '1', 12:21:13 INFO - 'PAGER': '/bin/cat', 12:21:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:13 INFO - 'PWD': '/builds/slave/test', 12:21:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:13 INFO - 'SHELL': '/bin/bash', 12:21:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zwPx9kZVhX/Listeners', 12:21:13 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 12:21:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:13 INFO - 'USER': 'cltbld', 12:21:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:13 INFO - 'XPC_FLAGS': '0x0', 12:21:13 INFO - 'XPC_SERVICE_NAME': '0', 12:21:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:14 INFO - Downloading/unpacking jsonschema==2.5.1 12:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:21:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:21: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 12:21: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 12:21: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 12:21: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 12:21: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 12:21: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 12:21:17 INFO - Downloading functools32-3.2.3-2.tar.gz 12:21:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:21:17 INFO - warning: no files found matching '*.txt' 12:21:17 INFO - no previously-included directories found matching 'build' 12:21:17 INFO - no previously-included directories found matching 'dist' 12:21:17 INFO - no previously-included directories found matching '.git*' 12:21:17 INFO - Installing collected packages: jsonschema, functools32 12:21:17 INFO - Running setup.py install for functools32 12:21:17 INFO - warning: no files found matching '*.txt' 12:21:17 INFO - no previously-included directories found matching 'build' 12:21:17 INFO - no previously-included directories found matching 'dist' 12:21:17 INFO - no previously-included directories found matching '.git*' 12:21:17 INFO - Successfully installed jsonschema functools32 12:21:17 INFO - Cleaning up... 12:21:17 INFO - Return code: 0 12:21:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:21: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')]} 12:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21: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')]} 12:21:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:17 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 0x10629ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6d14a4900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106078d30>, '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 0x105f9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10606dbe8>, '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.jaLxXKW56L/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.zwPx9kZVhX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 12:21:17 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 12:21:17 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 12:21:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jaLxXKW56L/Render', 12:21:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:17 INFO - 'HOME': '/Users/cltbld', 12:21:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:17 INFO - 'LOGNAME': 'cltbld', 12:21:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:17 INFO - 'MOZ_NO_REMOTE': '1', 12:21:17 INFO - 'NO_EM_RESTART': '1', 12:21:17 INFO - 'PAGER': '/bin/cat', 12:21:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:17 INFO - 'PWD': '/builds/slave/test', 12:21:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:17 INFO - 'SHELL': '/bin/bash', 12:21:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zwPx9kZVhX/Listeners', 12:21:17 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 12:21:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:17 INFO - 'USER': 'cltbld', 12:21:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:17 INFO - 'XPC_FLAGS': '0x0', 12:21:17 INFO - 'XPC_SERVICE_NAME': '0', 12:21:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:21:17 INFO - Cleaning up... 12:21:17 INFO - Return code: 0 12:21:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:21: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')]} 12:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21: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')]} 12:21:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:17 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 0x10629ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6d14a4900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106078d30>, '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 0x105f9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10606dbe8>, '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.jaLxXKW56L/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.zwPx9kZVhX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 12:21:17 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 12:21:17 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 12:21:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jaLxXKW56L/Render', 12:21:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:17 INFO - 'HOME': '/Users/cltbld', 12:21:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:17 INFO - 'LOGNAME': 'cltbld', 12:21:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:17 INFO - 'MOZ_NO_REMOTE': '1', 12:21:17 INFO - 'NO_EM_RESTART': '1', 12:21:17 INFO - 'PAGER': '/bin/cat', 12:21:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:17 INFO - 'PWD': '/builds/slave/test', 12:21:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:17 INFO - 'SHELL': '/bin/bash', 12:21:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zwPx9kZVhX/Listeners', 12:21:17 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 12:21:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:17 INFO - 'USER': 'cltbld', 12:21:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:17 INFO - 'XPC_FLAGS': '0x0', 12:21:17 INFO - 'XPC_SERVICE_NAME': '0', 12:21:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:18 INFO - Downloading/unpacking blobuploader==1.2.4 12:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:20 INFO - Downloading blobuploader-1.2.4.tar.gz 12:21:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:21:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:21:20 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 12:21:20 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 12:21:20 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 12:21:20 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 12:21:20 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 12:21:20 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 12:21:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:21:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:21:21 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 12:21:21 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 12:21:21 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 12:21:21 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 12:21:21 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 12:21:21 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 12:21:21 INFO - Downloading docopt-0.6.1.tar.gz 12:21:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:21:22 INFO - Installing collected packages: blobuploader, requests, docopt 12:21:22 INFO - Running setup.py install for blobuploader 12:21:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:21:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:21:22 INFO - Running setup.py install for requests 12:21:22 INFO - Running setup.py install for docopt 12:21:22 INFO - Successfully installed blobuploader requests docopt 12:21:22 INFO - Cleaning up... 12:21:22 INFO - Return code: 0 12:21:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:21: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')]} 12:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21: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')]} 12:21:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:22 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 0x10629ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6d14a4900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106078d30>, '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 0x105f9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10606dbe8>, '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.jaLxXKW56L/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.zwPx9kZVhX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 12:21:22 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 12:21:22 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 12:21:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jaLxXKW56L/Render', 12:21:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:22 INFO - 'HOME': '/Users/cltbld', 12:21:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:22 INFO - 'LOGNAME': 'cltbld', 12:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:22 INFO - 'MOZ_NO_REMOTE': '1', 12:21:22 INFO - 'NO_EM_RESTART': '1', 12:21:22 INFO - 'PAGER': '/bin/cat', 12:21:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:22 INFO - 'PWD': '/builds/slave/test', 12:21:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:22 INFO - 'SHELL': '/bin/bash', 12:21:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zwPx9kZVhX/Listeners', 12:21:22 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 12:21:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:22 INFO - 'USER': 'cltbld', 12:21:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:22 INFO - 'XPC_FLAGS': '0x0', 12:21:22 INFO - 'XPC_SERVICE_NAME': '0', 12:21:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:21:23 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-rMHgZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:21:23 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-RLtwQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:21:23 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-k232nM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:21:23 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-mhCBZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:21:23 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-m44a4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:21:23 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-8t1i3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:21:23 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-Ipnwi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:21:23 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-CPUWaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:21:24 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-Ft4iat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:21:24 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-e0Ja2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:21:24 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-kELlXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:21:24 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-FFTUBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:21:24 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-KAgZXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:21:24 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-JW3BSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:24 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-K7davk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:21:24 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-hoehOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:21:24 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-Rd95Cb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:21:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:21:24 INFO - Running setup.py install for manifestparser 12:21:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:21:25 INFO - Running setup.py install for mozcrash 12:21:25 INFO - Running setup.py install for mozdebug 12:21:25 INFO - Running setup.py install for mozdevice 12:21:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:21:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:21:25 INFO - Running setup.py install for mozfile 12:21:25 INFO - Running setup.py install for mozhttpd 12:21:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:21:25 INFO - Running setup.py install for mozinfo 12:21:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:21:26 INFO - Running setup.py install for mozInstall 12:21:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:21:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:21:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:21:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:21:26 INFO - Running setup.py install for mozleak 12:21:26 INFO - Running setup.py install for mozlog 12:21:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:21:26 INFO - Running setup.py install for moznetwork 12:21:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:21:26 INFO - Running setup.py install for mozprocess 12:21:26 INFO - Running setup.py install for mozprofile 12:21:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:21:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:21:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:21:26 INFO - Running setup.py install for mozrunner 12:21:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:21:27 INFO - Running setup.py install for mozscreenshot 12:21:27 INFO - Running setup.py install for moztest 12:21:27 INFO - Running setup.py install for mozversion 12:21:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:21:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:21:27 INFO - Cleaning up... 12:21:27 INFO - Return code: 0 12:21:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:21:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:27 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 0x10629ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6d14a4900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106078d30>, '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 0x105f9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10606dbe8>, '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.jaLxXKW56L/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.zwPx9kZVhX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 12:21:27 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 12:21:27 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 12:21:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jaLxXKW56L/Render', 12:21:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:27 INFO - 'HOME': '/Users/cltbld', 12:21:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:27 INFO - 'LOGNAME': 'cltbld', 12:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:27 INFO - 'MOZ_NO_REMOTE': '1', 12:21:27 INFO - 'NO_EM_RESTART': '1', 12:21:27 INFO - 'PAGER': '/bin/cat', 12:21:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:27 INFO - 'PWD': '/builds/slave/test', 12:21:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:27 INFO - 'SHELL': '/bin/bash', 12:21:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zwPx9kZVhX/Listeners', 12:21:27 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 12:21:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:27 INFO - 'USER': 'cltbld', 12:21:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:27 INFO - 'XPC_FLAGS': '0x0', 12:21:27 INFO - 'XPC_SERVICE_NAME': '0', 12:21:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:21:27 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-FmjGxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:21:27 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)) 12:21:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:21:27 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-OSRy0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:21:28 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)) 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:21:28 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-SG3m85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:21:28 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)) 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:21:28 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-1c7xaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:21:28 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)) 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:21:28 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-gtlVaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:21:28 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)) 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:21:28 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-U8Xsc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:21:28 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)) 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:21:28 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-wHYJtn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:21:28 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)) 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:21:28 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-LO9aiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:21:28 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)) 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:21:28 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-hY0Nh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:21:28 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)) 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:21:28 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-2V2jIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:21:28 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)) 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:21:28 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-c8dIqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:21:28 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)) 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:21:28 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-ASO5wI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:21:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:21:29 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-LjmYnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:21:29 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)) 12:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:21:29 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-uDuVJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:21:29 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)) 12:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:29 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-pXgHwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:29 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)) 12:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:21:29 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-R7Z2R4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:21:29 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)) 12:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:21:29 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-hJkfPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:21:29 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)) 12:21:29 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)) 12:21:29 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)) 12:21:29 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)) 12:21:29 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)) 12:21:29 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)) 12:21:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:32 INFO - Downloading blessings-1.6.tar.gz 12:21:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:21:32 INFO - Installing collected packages: blessings 12:21:32 INFO - Running setup.py install for blessings 12:21:32 INFO - Successfully installed blessings 12:21:32 INFO - Cleaning up... 12:21:32 INFO - Return code: 0 12:21:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:21:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:21:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:21:33 INFO - Reading from file tmpfile_stdout 12:21:33 INFO - Current package versions: 12:21:33 INFO - blessings == 1.6 12:21:33 INFO - blobuploader == 1.2.4 12:21:33 INFO - docopt == 0.6.1 12:21:33 INFO - functools32 == 3.2.3-2 12:21:33 INFO - jsonschema == 2.5.1 12:21:33 INFO - manifestparser == 1.1 12:21:33 INFO - mozInstall == 1.12 12:21:33 INFO - mozcrash == 1.0 12:21:33 INFO - mozdebug == 0.1 12:21:33 INFO - mozdevice == 0.49 12:21:33 INFO - mozfile == 1.2 12:21:33 INFO - mozhttpd == 0.7 12:21:33 INFO - mozinfo == 0.9 12:21:33 INFO - mozleak == 0.1 12:21:33 INFO - mozlog == 3.4 12:21:33 INFO - moznetwork == 0.27 12:21:33 INFO - mozprocess == 0.23 12:21:33 INFO - mozprofile == 0.28 12:21:33 INFO - mozrunner == 6.13 12:21:33 INFO - mozscreenshot == 0.1 12:21:33 INFO - mozsystemmonitor == 0.3 12:21:33 INFO - moztest == 0.8 12:21:33 INFO - mozversion == 1.4 12:21:33 INFO - psutil == 3.1.1 12:21:33 INFO - requests == 1.2.3 12:21:33 INFO - wsgiref == 0.1.2 12:21:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:21:33 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')]} 12:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:33 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')]} 12:21:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:33 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 0x10629ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6d14a4900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106078d30>, '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 0x105f9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10606dbe8>, '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.jaLxXKW56L/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.zwPx9kZVhX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 12:21:33 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 12:21:33 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 12:21:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jaLxXKW56L/Render', 12:21:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:33 INFO - 'HOME': '/Users/cltbld', 12:21:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:33 INFO - 'LOGNAME': 'cltbld', 12:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:33 INFO - 'MOZ_NO_REMOTE': '1', 12:21:33 INFO - 'NO_EM_RESTART': '1', 12:21:33 INFO - 'PAGER': '/bin/cat', 12:21:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:33 INFO - 'PWD': '/builds/slave/test', 12:21:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:33 INFO - 'SHELL': '/bin/bash', 12:21:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zwPx9kZVhX/Listeners', 12:21:33 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 12:21:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:33 INFO - 'USER': 'cltbld', 12:21:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:33 INFO - 'XPC_FLAGS': '0x0', 12:21:33 INFO - 'XPC_SERVICE_NAME': '0', 12:21:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:33 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)) 12:21:33 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)) 12:21:33 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)) 12:21:33 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)) 12:21:33 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)) 12:21:33 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)) 12:21:33 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)) 12:21:33 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)) 12:21:33 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)) 12:21:33 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)) 12:21:33 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)) 12:21:33 INFO - Cleaning up... 12:21:33 INFO - Return code: 0 12:21:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:21:33 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')]} 12:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:33 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')]} 12:21:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:33 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 0x10629ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6d14a4900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106078d30>, '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 0x105f9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10606dbe8>, '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.jaLxXKW56L/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.zwPx9kZVhX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 12:21:33 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 12:21:33 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 12:21:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jaLxXKW56L/Render', 12:21:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:33 INFO - 'HOME': '/Users/cltbld', 12:21:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:33 INFO - 'LOGNAME': 'cltbld', 12:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:33 INFO - 'MOZ_NO_REMOTE': '1', 12:21:33 INFO - 'NO_EM_RESTART': '1', 12:21:33 INFO - 'PAGER': '/bin/cat', 12:21:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:33 INFO - 'PWD': '/builds/slave/test', 12:21:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:33 INFO - 'SHELL': '/bin/bash', 12:21:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zwPx9kZVhX/Listeners', 12:21:33 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 12:21:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:33 INFO - 'USER': 'cltbld', 12:21:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:33 INFO - 'XPC_FLAGS': '0x0', 12:21:33 INFO - 'XPC_SERVICE_NAME': '0', 12:21:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:21:33 INFO - Cleaning up... 12:21:33 INFO - Return code: 0 12:21:33 INFO - Running post-action listener: _resource_record_post_action 12:21:33 INFO - Running post-action listener: _start_resource_monitoring 12:21:33 INFO - Starting resource monitoring. 12:21:33 INFO - [mozharness: 2016-12-31 20:21:33.603726Z] Finished create-virtualenv step (success) 12:21:33 INFO - [mozharness: 2016-12-31 20:21:33.604609Z] Running install step. 12:21:33 INFO - Running pre-action listener: _resource_record_pre_action 12:21:33 INFO - Running main action method: install 12:21:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:21:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:21:33 INFO - Reading from file tmpfile_stdout 12:21:33 INFO - Detecting whether we're running mozinstall >=1.0... 12:21:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:21:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:21:33 INFO - Reading from file tmpfile_stdout 12:21:33 INFO - Output received: 12:21:33 INFO - Usage: mozinstall [options] installer 12:21:33 INFO - Options: 12:21:33 INFO - -h, --help show this help message and exit 12:21:33 INFO - -d DEST, --destination=DEST 12:21:33 INFO - Directory to install application into. [default: 12:21:33 INFO - "/builds/slave/test"] 12:21:33 INFO - --app=APP Application being installed. [default: firefox] 12:21:33 INFO - mkdir: /builds/slave/test/build/application 12:21:33 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'] 12:21:33 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 12:21:55 INFO - Reading from file tmpfile_stdout 12:21:55 INFO - Output received: 12:21:55 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:21:55 INFO - Running post-action listener: _resource_record_post_action 12:21:55 INFO - [mozharness: 2016-12-31 20:21:55.582789Z] Finished install step (success) 12:21:55 INFO - [mozharness: 2016-12-31 20:21:55.582933Z] Running run-tests step. 12:21:55 INFO - Running pre-action listener: _resource_record_pre_action 12:21:55 INFO - Running main action method: run_tests 12:21:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:21:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:21:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:21:55 INFO - 2016-12-31 12:21:55.597 screenresolution[1635:7811] starting screenresolution argv=screenresolution get 12:21:55 INFO - 2016-12-31 12:21:55.599 screenresolution[1635:7811] Display 0: 1600x1200x32@60 12:21:55 INFO - 2016-12-31 12:21:55.605 screenresolution[1636:7814] starting screenresolution argv=screenresolution list 12:21:55 INFO - Available Modes on Display 0 12:21:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:21:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:21:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:21:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:21:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:21:55 INFO - Intel Iris: 12:21:55 INFO - Chipset Model: Intel Iris 12:21:55 INFO - Type: GPU 12:21:55 INFO - Bus: Built-In 12:21:55 INFO - VRAM (Dynamic, Max): 1536 MB 12:21:55 INFO - Vendor: Intel (0x8086) 12:21:55 INFO - Device ID: 0x0a2e 12:21:55 INFO - Revision ID: 0x0009 12:21:55 INFO - Displays: 12:21:55 INFO - Display: 12:21:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:21:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:21:55 INFO - Display Serial Number: 1600x1200 60 12:21:55 INFO - Main Display: Yes 12:21:55 INFO - Mirror: Off 12:21:55 INFO - Online: Yes 12:21:55 INFO - Rotation: Supported 12:21:55 INFO - Return code: 0 12:21:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:21:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:21:55 INFO - Python 2.7.3 12:21:55 INFO - Return code: 0 12:21:55 INFO - grabbing minidump binary from tooltool 12:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:55 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 0x106078d30>, '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 0x105f9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10606dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:21:55 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 12:21:55 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 12:21:55 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 12:21:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:21:55 INFO - Return code: 0 12:21:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:21:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:21:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:21:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:21:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:21:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0079', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:21:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0079 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:21:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jaLxXKW56L/Render', 12:21:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:55 INFO - 'HOME': '/Users/cltbld', 12:21:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:55 INFO - 'LOGNAME': 'cltbld', 12:21:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:21:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:55 INFO - 'MOZ_NO_REMOTE': '1', 12:21:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:21:55 INFO - 'NO_EM_RESTART': '1', 12:21:55 INFO - 'PAGER': '/bin/cat', 12:21:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:55 INFO - 'PWD': '/builds/slave/test', 12:21:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:21:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:55 INFO - 'SHELL': '/bin/bash', 12:21:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zwPx9kZVhX/Listeners', 12:21:55 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 12:21:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:55 INFO - 'USER': 'cltbld', 12:21:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:55 INFO - 'XPC_FLAGS': '0x0', 12:21:55 INFO - 'XPC_SERVICE_NAME': '0', 12:21:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0079', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:21:56 INFO - mozversion application_buildid: 20161231105319 12:21:56 INFO - mozversion application_changeset: ff4defb6f945e9bfc22ac4b465b3f3d88567da07 12:21:56 INFO - mozversion application_display_name: Nightly 12:21:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:21:56 INFO - mozversion application_name: Firefox 12:21:56 INFO - mozversion application_remotingname: firefox 12:21:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:21:56 INFO - mozversion application_vendor: Mozilla 12:21:56 INFO - mozversion application_version: 53.0a1 12:21:56 INFO - mozversion platform_buildid: 20161231105319 12:21:56 INFO - mozversion platform_changeset: ff4defb6f945e9bfc22ac4b465b3f3d88567da07 12:21:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:21:56 INFO - mozversion platform_version: 53.0a1 12:21:56 INFO - using testdate: 1483215716 12:21:56 INFO - actual date: 1483215716 12:21:56 INFO - starting webserver on 'localhost:49243' 12:21:56 INFO - SUITE-START | Running 2 tests 12:21:56 INFO - TEST-START | dromaeo_css 12:21:56 INFO - operating with platform_type : mac_ 12:21:56 INFO - Initialising browser for dromaeo_css test... 12:21:56 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmp0CWIIR/profile http://localhost:49243/getInfo.html) 12:21:56 INFO - PROCESS | 1646 | 1483215716721 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 12:21:58 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 12:21:58 INFO - PROCESS | 1646 | colorDepth:24 12:21:58 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 12:21:58 INFO - PROCESS | 1646 | __metrics 12:21:58 INFO - PROCESS | 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:21:58 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:21:59 INFO - TEST-INFO | 1646: exit 0 12:21:59 INFO - Browser initialized. 12:21:59 INFO - Running cycle 1/1 for dromaeo_css test... 12:21:59 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmp0CWIIR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:22:11 INFO - PROCESS | 1648 | RSS: Main: 234012672 12:22:11 INFO - PROCESS | 1648 | 12:23:02 INFO - PROCESS | 1648 | 1483215782403 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:25:15 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 12:25:17 INFO - PROCESS | 1648 | RSS: Main: 325365760 12:25:17 INFO - PROCESS | 1648 | 12:27:00 INFO - PROCESS | 1648 | ************************* 12:27:00 INFO - PROCESS | 1648 | A coding exception was thrown and uncaught in a Task. 12:27:00 INFO - PROCESS | 1648 | 12:27:00 INFO - PROCESS | 1648 | Full message: ReferenceError: fetch is not defined 12:27:00 INFO - PROCESS | 1648 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 12:27:00 INFO - PROCESS | 1648 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 12:27:00 INFO - PROCESS | 1648 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 12:27:00 INFO - PROCESS | 1648 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 12:27:00 INFO - PROCESS | 1648 | TaskImpl@resource://gre/modules/Task.jsm:277:3 12:27:00 INFO - PROCESS | 1648 | asyncFunction@resource://gre/modules/Task.jsm:252:14 12:27:00 INFO - PROCESS | 1648 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 12:27:00 INFO - PROCESS | 1648 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 12:27:00 INFO - PROCESS | 1648 | TaskImpl@resource://gre/modules/Task.jsm:277:3 12:27:00 INFO - PROCESS | 1648 | asyncFunction@resource://gre/modules/Task.jsm:252:14 12:27:00 INFO - PROCESS | 1648 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 12:27:00 INFO - PROCESS | 1648 | 12:27:00 INFO - PROCESS | 1648 | ************************* 12:28:20 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 12:28:21 INFO - PROCESS | 1648 | RSS: Main: 363843584 12:28:21 INFO - PROCESS | 1648 | 12:31:23 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 12:31:24 INFO - PROCESS | 1648 | RSS: Main: 339537920 12:31:24 INFO - PROCESS | 1648 | 12:34:26 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 12:34:27 INFO - PROCESS | 1648 | RSS: Main: 339288064 12:34:27 INFO - PROCESS | 1648 | 12:37:30 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 12:37:31 INFO - PROCESS | 1648 | RSS: Main: 335335424 12:37:31 INFO - PROCESS | 1648 | 12:40:34 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 12:40:34 INFO - PROCESS | 1648 | RSS: Main: 422641664 12:40:34 INFO - PROCESS | 1648 | 12:40:34 INFO - PROCESS | 1648 | __start_tp_report 12:40:34 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 12:40:34 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 12:40:34 INFO - PROCESS | 1648 | |i|pagename|runs| 12:40:34 INFO - PROCESS | 1648 | |0;dojo.html;3327.118313646884;3333.49997500375;3352.949705754414;3357.8153201573905;3416.812075335857;5572.637778544402;5637.943620563793;5651.463111004453;5731.971340143298;5759.654420734753;5647.802326918559;5684.801031963882;5711.543076553876;5718.9428105718935;5726.79956201532;5163.561097306733;5180.974095129519;5197.896042079156;5224.138017227153;5234.241035049916;5166.974165129169;5279.340082489689;5287.048331300364;5311.654742441749;5328.893422131555;5270.81552145675;5285.497877701612;5296.655717378378;5302.2841916341185;5340.439253878345;5105.081085404626;5159.71621560814;5177.534021938043;5231.581473482112;5259.5529380002745;5146.150885103953;5184.8963020739375;5186.325777648919;5195.584353251731;5283.365996080456;5706.28671416073;5724.39893811148;5725.6278341908055;5733.827985160452;5749.511291540224;5425.6202065855405;6053.576249662526;6249.406306400884;6259.155014073087;6266.122742816009;6149.739303442783;6157.014877619604;6240.500759939194;6241.3446588108045;6276.686165691697;5756.740946657411;5813.709314534341;5825.766969321265;5856.414358564152;5869.090291004901;6228.657423841732;6296.43332100104;6297.874042519124;6309.936900631027;6311;5901.734421950937;5946.643201407929;5953.672548009901;6156.168917196122;6189.752409903644;6168.25980882297;6208.379162083756;6226.221722284715;6279.529035322369;6290.150829638032;6065.54508411862;6100.115483254903;6108.13739084631;6129.233845769279;6130.203073600493;5003.224500202475;6139.216166136444;6184.226971628546;6197.659128747872;6208.751649933952;4840.419149702058;4911.8280860169725;4912.140375434285;4922.990786888679;4953.6037117030555;4400.823967041282;4440.245158323093;4466.9106617867465;4483.708558943724;4502.032063106382;4829.420469543677;5778.682172480554;5799.18811366409;5799.797007104731;5803.88392232153;6187.566870319124;6196.132541444202;6216.191895053614;6240.781572645026;6298.244210694655;5684.516816070598;5782;5834.620749651259;5844.064949608042;5876.794312199053;5318.25544423781;5335.839924802262;5395.946040539544;5403.648762830403;5412.242286079952;6223.564350495512;6230.626162430269;6270.933941230001;6271.090691849748;6347.619142851444;6256.906146407717;6270.090836828543;6300.306966233802;6308.653024083719;6319.715612797527;6277.1525844010475;6285;6285.685715714288;6301.464375528131;6307.432331090224;6054.122152287806;6112.083187521729;6197.9690101551005;6229.2524897012645;6237.5321850860455;5155.510226528474;6188.44304012641;6213.161223234986;6308.684565771601;6313.747450101944;6136.2636483622255;6150.7847225346895;6153.784617538365;6162.661053641913;6165.445109940127;5777.595568310178;6085.939140608714;6099.146119543178;6147.139400483847;6158.168647232746;5734.856628584319;5889.146073819358;5896.852578685395;5934.376890426615;6044.274687037584;5906.616069955439;6150.04674275488;6154.107654390178;6159.1069294951085;6166.969165154145;5743.368229494836;5816.94183058164;5844.6785426801935;5859.472647461749;5894.941050589439 12:40:34 INFO - PROCESS | 1648 | |1;ext.html;413.5306427205122;486.2099088980407;500.859759267405;502.96982181069137;505.95699365553924;30151.547726784087;31662.366752664933;31709.68290317099;31710.99999999997;39168;39330.60669393312;41489.7925510372;41516;41535;41695;9244.260459163252;9446.716598502022;9463.432194068344;9467.857982130274;9476.336656434052;8448.281896038814;8658;9291.814163716721;9396.81206375872;9408.952955235249;8797.912020879805;9449.81100377992;11458.942705286461;11640.18518703691;11642.30146191227;11271.154663400277;11351.091912647009;11364.772704545863;11370.431478426128;11372.37451940143;8368.372372072057;8553.914460855374;10940.288881222774;11137.05335046522;11214;37507.24985500302;39189.41215881764;39200.41199382012;39219;39357.80321098405;3949.2299001694682;3957.7823219722904;3966.940495892564;3986.4618276532588;4090.386442033689;24516.87741561281;30446.15514425958;30642;30643.540346894813;30723.231919201975;3129.9687003129907;3136.5765621641012;3295.0938491914676;3341.398548261312;3346.4311067118647;338594.30702846573;339871;340886;341359;342972;54679.72660136674;57175;57263.427365726646;57381.42618573761;57689.13466298009;58981.705091474265;60883;61034.084488732704;61049.08426373608;61150.38849611536;14958.551243462714;15482.148481833607;15553.377864885504;15553.611159721098;15597.298121584556;11955.940220299017;12015.939920300343;12027.21823081497;12027.21823081497;12037.759244815055;13981.23103229332;14333.283335833166;15650.765238521431;15980.121093339745;16020.599485012968;5537.446255374511;6501.707423165969;6574.769883054166;6628.602283862887;6679.3320667933785;864.7967727584013;866.6836604639387;876.4784952952983;879.8856148700628;897.228383590112;29194.270143246165;29228.26929326784;29549.852250738608;29952;30412.087637370914;1038.2472707287156;1040.776233109885;1068.3643232276784;1076.9730756730987;1155.1682788392345;13882.51411200603;14393.496227631982;14622.780658290134;15182.392704291704;15392.008243418517;572471;575330;578460;579008;579316;49884.50115498871;57557;57643;57701;57728;1381.9654508637163;1401.7336706025822;1415.709779495201;1422.096968425037;1422.4310275889727;26318.736812632396;29003.12990610285;29483;29530.852345738134;29611.407771844013;47363.76318118387;48823.5117648819;51789.7410512945;51790.741046294526;51921;559530;563983;564669;564963;565491;19612.215511379385;24461.755382445946;24492.6326105105;24515.877420613495;24554.877225614473;33897.830510847285;40262;42144.578554214066;42476;43591.58942979765;35097.29805403929;35146.64853351434;35280.47079293864;36167.638323616426;36315;34681.47977780285;34798.9560313191;35350.46974295336;35827.10432239215;35917.461238081974;33047.834760827005;33071.50392744159;33827.154321141184;34284.31431371309;34332.82833585816;30382.544261835646;31683.20791980219;31750.52374214435;31820.5226921601;31913.202169945936;20478.795212048284;23530.647040294753;23595.29214123579;23867;23982.88008559946 12:40:34 INFO - PROCESS | 1648 | |2;jquery.html;336.82653433481755;355.1122194513716;369.3978814532312;378.79923640470554;398.5038626909498;14477.710445791077;14867.851321486782;15449.768253476206;19288.710669339973;19811.40565783025;18414.72377914329;19164.90417547914;19405;19495;19675;5043.344365232524;5320.840374788762;5325.627206095575;5334.626576139672;5346.064438723227;4965.7020578765205;5260.605454590902;5467.644603100805;5793.565482588801;5860.560457965648;5807.709614519278;5808;5814.651120932736;5825.135956493924;5830.912536311958;5543.390227075019;5787.71061446926;5817.767289308423;5889.941100588982;5934.762609495615;5492.972535137339;5631.042722737145;5719.971400143014;5723.141528770676;5750.6262092963825;12718.936405317914;12760.680982975407;12835.101542892002;12877.227366358049;13078.607641770763;2607.087519368225;2632.697239817426;2634.5257853586345;2640.696319923195;2717.184844546648;9679.225661947028;9710.271729620306;10228.488575571191;13145.211287322696;13494;1795.703708888032;1797.0924683035114;1797.1014492753623;1814.5645045189192;1819.654265689528;108453.45773271163;113459.43270283595;113876;114114;115564;9309.953450232706;11229.3262404256;11376.886231137583;11552.249103808226;11591.76816463683;14385;14393.92803035978;14402.711945761026;14418.855811441961;14454.85545144556;7992.680292788224;8128.349732021424;8137.75586732399;8244.381671374671;8267.131951145164;7598.164201937782;7867.409944254204;7928.643211055516;8058.476199047043;8092.595370231465;3626.673599376016;4376.803043863034;4472.1502914446155;4510.50384457716;4523.36672865799;1846.8060853610177;1918.1176658736858;1930.2338360400358;1933.2750218668;1934.458351661537;935.1396715022196;943.1610851337523;945.7824700318965;956.7368973532334;962.8122516109291;11488.368139752394;11772.52909883595;11790.705232369259;12154.270743755404;18179.63640727178;407.68811858927944;427.1852358439168;439.2379222049741;451.0775464175703;458.21873705332996;4852.223644216909;5767.336756273083;5829.88340233193;5835.153902684087;6028.276606807123;530666;535421;546538;547957;551334;129914;131121;131254;133276;133367;445.54554354558815;450.5359479735877;453.5713750505806;453.92737162053913;454.5908682186059;8144;10622.415767132881;10943.616973405893;10944.56221751153;11008.504617292401;43752;45983.54016459793;51704;53057.20414193713;54395;75959.62020189864;76042.23957760351;76773.61613191899;77007.22992770001;77808;11402.144839136932;11691.707707307385;12068.396580171131;12139.39303034827;12151.878481215075;12979.35103244815;13153.01352398593;13207.66980825487;13666.11170273929;13703.862961370258;9962.900370996198;10244.565760793346;11141.387223702774;11722.944934955894;11841.82237266459;11234.528403751161;11250.943745281222;11395.943020284845;11456.369899655265;11686.18196726221;9495;11703.2978021319;11912.880871191524;12043.879561204276;12051.517939282681;10324.761028676607;11555.4800033997;11674.357910315015;11736.941315293368;11980.760384792082;11266.380349080551;11541.769164616493;11600.593979210687;11765.941065304163;11790 12:40:34 INFO - PROCESS | 1648 | |3;mootools.html;1295.0546101346024;1381.3300549233622;1384.224834092908;1404.97892531612;1407.5297852269266;7328.230535793738;7706.344960678349;7964.044314682246;8215.71245006425;8217.958910205441;8036.678532858678;8121.959390203056;8129.146439623828;8136.674533018687;8141.837163256746;2042.77529471758;2112.767595564487;2273.5793878132517;2309.9884500577473;2315.22439982606;2264.739554951177;2342.852002518762;2347.213683416058;2360.3155085025323;2366.7869891709743;2280.646499792535;2299.7815207555254;2307.792298693117;2309;2316.328264803205;2297.276357947241;2298.0233400804673;2322.314917099443;2323.303009097264;2324.1400681747778;1902.9533756433907;2238.160689741347;2317.8493397929083;2332.685087513181;2338.1933233034574;8157.75526734199;8253.83492330156;8255.587220639005;8261.545614991173;8265.545395003272;4959.156943319608;5054.292399064134;5089.923651145235;5126.333576635051;5172.353455818023;2789.4839454700946;3315;3517.7713448626014;3519.753617246794;3522.3659741246565;1032.8140934631763;1207.6739280394331;1239.6962744127748;1254.5797157952097;1266.5820279307898;10619.681409557727;10722.463876806207;10734.355938643706;10739.731506712316;10752.999999999922;4158.251514727378;4366.847160349373;4514.435695538058;4602.7698615069785;4603.677742558056;9212.401193922373;9221.262299016063;9245.768855778524;9247.76880577991;9275.67535136267;5058.721770302595;5095.617828662865;5096.413912400048;5105.361829771278;5116.697486131256;3712.832922518493;4188.10791516699;4452.577005184502;4718.4809670936165;4721.386219791405;3726.1057346236707;3736.3274610570356;3830.9808450958126;3855.4602355670227;3855.7108216883844;1245.4893493667553;1258.937053147339;1263.911526193176;1270.6950331920273;1270.8284381608364;767.54331172952;770.1412924589043;777.3975169243881;791.2008871040176;796.0646240667309;4386.758728269912;5404.189371594292;5473.261109750212;5508.91736623951;5587.69267690281;537.4169026606098;549.8982688202695;556.4407770190933;562.0697745231648;566.3854717631416;3123.937521249608;3716.7769933804057;3780.0738818989525;3797.9620203798163;3838.731288809812;9459.90540094604;9681.273904457054;9784.608615655436;9815.705528834147;9821.558029888673;9655.9034409655;9683.176929961031;9688.32181747271;9795.06946840049;9845;7572.697092116364;7637.236276372319;7722.922770772333;7735.497192682457;7746.147923728386;5409.891802163856;5419.485148910847;5461.235427040138;5484.341878974548;5497.340319161726;8961.596728147118;9036.23192028685;9099.090090990849;9102;9110.863337049817;8572.099929507312;9129.726108216764;9140.817183656423;9194.632214711604;9212.585433655368;6461.127747754181;6628.7017084230365;6666.899996499959;6949.339812717783;7100;7656.617169141409;7749.612519374122;7835.647395867314;7840.764777056697;7851.803704907423;6641.169853768279;6720.798376048726;6746.325367463214;6787.490938179755;6880.071190389233;6872.553284036521;6880.901325095894;6897.7240910363025;6984.720611175496;7000.124984376953;6219.440250377488;6284.528660350401;6383.96808015957;6402.103705481143;6410.967945160244;4423.734575925326;4431.246688062973;4488.371627972021;4504.346869703809;4579.152856721517;4627.074585083064;4785.066911952136;4815.085133824562;4876.512348765095;4915.827946021872 12:40:34 INFO - PROCESS | 1648 | |4;prototype.html;352.918828669406;362.96370362963705;365.32050386281526;371.17413754396455;372.6943905997082;4715.481297057321;5335.386430560487;5337.893242135155;5353.304070470843;5406.351237851459;5123.359580052494;5253.6322457428;5290.444503327153;5293.6294459387855;5307.867303317419;3351.3632409842176;3396.7961922284617;3750.4374343848503;3807.733458657888;4108.548059713429;3242.578464799573;3356.429407000816;3386.3904497190497;4085.9795701021453;4117.8764637060785;4131.421600975866;4134.338505839067;4137.958620413802;4141.343462614948;4164.9583504164875;3863.690904727629;3948.5064366954275;4023.2556976959354;4120.587941205856;4162.875113746592;3150.385674793416;3629.6007439181667;3629.745917785756;3633.6184700606586;3651.6896063834606;5158.329417175743;5161.251618515332;5182.404023537304;5182.766775495112;5192.61055420845;4605.4933957264675;4703.7883295251795;4743.976280118612;4756.429228492603;4786.880327991793;5117.641765076446;5555.722213889289;5570.86072848182;5603.439656034405;5628.380878103405;3524.3303772283184;3588.802615856127;3731.9253614927547;4048.67610591152;4056.188762247533;5514.531264842493;5824.359320474744;5847.970760146172;5934.643921364732;5941.554383421218;3364.8149351785396;3835;3845.288621605012;3846.0385503162406;3858.8070596470066;3631.910426871928;3883.8446462141196;3893;3908.843646254118;3927.9394563467704;3936.527616686016;3985.2826491231276;4295;4348.608625223745;4504.144212599661;5037.496250375007;5514.255575497256;5580.637258578179;5585.720713964285;5614.887702245932;1254.5859866244118;1258.660161756339;1265.436880588148;1271.7520083583797;1278.1883503974857;751.9097708275042;781.8201813582908;798.6845196147606;849.2696281198165;865.2515574028546;4391.560843915647;4395.648348132206;4938.358013458227;4942.728149951788;4953.72754498506;5049.606842047368;5436.864078398072;5446;5464.377098992506;5487.862803429946;4413.801378937956;4471.396361491157;4631.652626053059;4918.336024636628;4942.57988071018;2464.5705490815285;2632.8551929644054;2636.736326367348;2931.2525306047246;2956.911292661224;4709.222978208626;5294.92057619136;5752.223449834302;5767.163761254595;5774.498630356054;4971.4282857471135;4990.575801056878;5301.83359660874;5355.410904800468;5704.543636509153;4977.701337919736;5306.071437498423;5472.808451704092;5744.4829965303325;5778.711064446761;4853.975730121327;5006.574441172541;5384.353877534721;5500.834974950758;5529.695866727368;5296.973515132399;5478.891240963317;5710.430306362071;5721.05602575587;5753.223314852441;5024.4473107957365;5294.788208471618;5993.766482857779;5997.91003134962;6012.759489620541;3368.8714280716235;3863.787491687872;3888.2806680764147;3936.9015774605864;3961.801909904551;5126.410462796826;5778.8266352009505;5817.738201781015;5835.649861008353;5835.9708201458725;4905.705657660552;5026.0199261143725;5486.588505862156;5580.441955804549;5687.601867869375;4732.645051621073;5431.864203394789;5539.421264939634;5659.575956546395;5710.172025056427;5505.981393442226;5513.889722205614;5517.586181036358;5521.779128834802;5594.943665842395;5616.1856530803625;5623.0721930880945;5623.297087864017;5664.575156863334;5677.148427735872;4336.566343365638;5091.974540127276;5386.384362237836;5470.316210473691;5611.80358687444 12:40:34 INFO - PROCESS | 1648 | |5;yui.html;696.7004188199076;712.8823744082226;752.428154602502;786.6302837666296;800.8678568036272;14331.856681433183;15434.61413464664;15472.845271547281;15486;15491.5352539424;13930.51243206488;14140.929295353535;14144.292785360742;14148.858511414883;14173.936954728386;685.9416949559294;703.0017375327047;704.5138854190618;834.4784509681449;836.8493671139192;862.6851199312238;868.3053557154251;882.8631562107884;884.5488800711652;886.1006078829993;936.1013427109982;940.2337095267369;940.3323640215456;942.9009953954838;943.7923656795494;719.0975325965932;741.4328039050131;928.9688445825128;932.3240650528346;933.4632586262858;558.9105743081128;558.9496945274944;561.0714267886655;659.3768888400464;678.1184460201719;14642;14715.190664513448;14729.70540589193;14733.189674567895;14734.926325368411;12306.692332691664;12345.62963111099;12687.41875626387;12762.617121486277;12859.228446293158;10504.369737815756;12327.137100402975;12599.748005039848;12749.10756247063;13048.804267935988;4377.627901628365;4399.802008909607;4423.40284061654;4430.689851710382;4581.473130589991;110000.4499977503;115241.68028914678;116182.41908790403;116311;116685.83314166835;7684.615769211517;8817.29461643067;9043.728688139367;9180.586873590704;9273.211776998896;17568.38510652121;17821.821781782277;18149.455516334532;18262.54343641393;18428.986405747546;6519.706613202417;7367.115946086396;7430.294122058396;7542.170361260257;7681.078270607562;4098.180363927227;4132.855350062733;4242.427272318259;4249.702520823513;4251.17102165081;3266.428375034407;3336.115929278743;3348.2466445049668;3379.5974670511955;3381.7970921744773;643.6009674002148;645.6029541831833;652.3183273479226;658.8930596597665;658.9901151482732;551.4154995704566;565.2679779685317;576.2479963647396;596.5704692621306;623.8178651455557;9046.638134474679;9155.77110572241;9237.676681316256;9395.154436100784;9495.192908602708;627.5732501898661;639.8528338482082;654.9443297319685;659.6299515708432;660.8050625065617;3516.9648303516637;4250.787460627018;4390.165868484977;4408.6913916026215;4488.730676159441;104227;105232;108402;110169.44915275533;113330.43334783273;67952.98070528946;92066;93930;95258;95282.52358738161;82360;83798;83860;84194.57902710447;86021.56989215013;8440.24037836573;8699.956500217459;8704.651813927625;8720.956395217983;8970.23752981004;44258.77870610755;55480;56133.71933140308;56559.71720141373;56854.43145568491;73984.26015739773;74034.62982685052;74867.62566187134;75565;75719.62140189264;11388.829167562328;11486.770264594828;11513.021393181674;11586.76826463483;12027.579034734092;12485.625431237077;12519.248845069325;12777.552785652457;12800.35998200105;12836.486540538273;11667.941660291644;12019.879801201876;12094.153409261267;12121.81817272724;12477.126601137834;11721.706957326134;11947.641570752892;12042.819357709466;12059.15585908978;12552.309622970824;11643.650690479299;11822.34977076269;12120.33338166374;12201.02391808671;12442.191257568229;9275.39709918853;9900.25748068912;9930.404175749478;10260.486975651336;10289.228307876789;9136.451812891248;9255.814883702424;9808.950955245178;9846.113849753556;9891.35706179096 12:40:34 INFO - PROCESS | 1648 | __end_tp_report 12:40:34 INFO - PROCESS | 1648 | __start_cc_report 12:40:34 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1937 12:40:34 INFO - PROCESS | 1648 | __end_cc_report 12:40:34 INFO - PROCESS | 1648 | __startTimestamp1483216834450__endTimestamp 12:40:34 INFO - PROCESS | 1648 | 12:40:34 INFO - PROCESS | 1648 | ------- Summary: start ------- 12:40:34 INFO - PROCESS | 1648 | Number of tests: 6 12:40:34 INFO - PROCESS | 1648 | 12:40:34 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5720.70 Median:5869.09 stddev:634.38 (10.8%) stddev-sans-first:607.91 12:40:34 INFO - PROCESS | 1648 | Values: 3327.1 3333.5 3352.9 3357.8 3416.8 5572.6 5637.9 5651.5 5732.0 5759.7 5647.8 5684.8 5711.5 5718.9 5726.8 5163.6 5181.0 5197.9 5224.1 5234.2 5167.0 5279.3 5287.0 5311.7 5328.9 5270.8 5285.5 5296.7 5302.3 5340.4 5105.1 5159.7 5177.5 5231.6 5259.6 5146.2 5184.9 5186.3 5195.6 5283.4 5706.3 5724.4 5725.6 5733.8 5749.5 5425.6 6053.6 6249.4 6259.2 6266.1 6149.7 6157.0 6240.5 6241.3 6276.7 5756.7 5813.7 5825.8 5856.4 5869.1 6228.7 6296.4 6297.9 6309.9 6311.0 5901.7 5946.6 5953.7 6156.2 6189.8 6168.3 6208.4 6226.2 6279.5 6290.2 6065.5 6100.1 6108.1 6129.2 6130.2 5003.2 6139.2 6184.2 6197.7 6208.8 4840.4 4911.8 4912.1 4923.0 4953.6 4400.8 4440.2 4466.9 4483.7 4502.0 4829.4 5778.7 5799.2 5799.8 5803.9 6187.6 6196.1 6216.2 6240.8 6298.2 5684.5 5782.0 5834.6 5844.1 5876.8 5318.3 5335.8 5395.9 5403.6 5412.2 6223.6 6230.6 6270.9 6271.1 6347.6 6256.9 6270.1 6300.3 6308.7 6319.7 6277.2 6285.0 6285.7 6301.5 6307.4 6054.1 6112.1 6198.0 6229.3 6237.5 5155.5 6188.4 6213.2 6308.7 6313.7 6136.3 6150.8 6153.8 6162.7 6165.4 5777.6 6085.9 6099.1 6147.1 6158.2 5734.9 5889.1 5896.9 5934.4 6044.3 5906.6 6150.0 6154.1 6159.1 6167.0 5743.4 5816.9 5844.7 5859.5 5894.9 12:40:34 INFO - PROCESS | 1648 | 12:40:34 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:62624.86 Median:34507.15 stddev:135142.46 (391.6%) stddev-sans-first:135440.85 12:40:34 INFO - PROCESS | 1648 | Values: 413.5 486.2 500.9 503.0 506.0 30151.5 31662.4 31709.7 31711.0 39168.0 39330.6 41489.8 41516.0 41535.0 41695.0 9244.3 9446.7 9463.4 9467.9 9476.3 8448.3 8658.0 9291.8 9396.8 9409.0 8797.9 9449.8 11458.9 11640.2 11642.3 11271.2 11351.1 11364.8 11370.4 11372.4 8368.4 8553.9 10940.3 11137.1 11214.0 37507.2 39189.4 39200.4 39219.0 39357.8 3949.2 3957.8 3966.9 3986.5 4090.4 24516.9 30446.2 30642.0 30643.5 30723.2 3130.0 3136.6 3295.1 3341.4 3346.4 338594.3 339871.0 340886.0 341359.0 342972.0 54679.7 57175.0 57263.4 57381.4 57689.1 58981.7 60883.0 61034.1 61049.1 61150.4 14958.6 15482.1 15553.4 15553.6 15597.3 11955.9 12015.9 12027.2 12027.2 12037.8 13981.2 14333.3 15650.8 15980.1 16020.6 5537.4 6501.7 6574.8 6628.6 6679.3 864.8 866.7 876.5 879.9 897.2 29194.3 29228.3 29549.9 29952.0 30412.1 1038.2 1040.8 1068.4 1077.0 1155.2 13882.5 14393.5 14622.8 15182.4 15392.0 572471.0 575330.0 578460.0 579008.0 579316.0 49884.5 57557.0 57643.0 57701.0 57728.0 1382.0 1401.7 1415.7 1422.1 1422.4 26318.7 29003.1 29483.0 29530.9 29611.4 47363.8 48823.5 51789.7 51790.7 51921.0 559530.0 563983.0 564669.0 564963.0 565491.0 19612.2 24461.8 24492.6 24515.9 24554.9 33897.8 40262.0 42144.6 42476.0 43591.6 35097.3 35146.6 35280.5 36167.6 36315.0 34681.5 34799.0 35350.5 35827.1 35917.5 33047.8 33071.5 33827.2 34284.3 34332.8 30382.5 31683.2 31750.5 31820.5 31913.2 20478.8 23530.6 23595.3 23867.0 23982.9 12:40:34 INFO - PROCESS | 1648 | 12:40:34 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:32428.67 Median:2633.61 stddev:91208.37 (3463.2%) stddev-sans-first:91432.40 12:40:34 INFO - PROCESS | 1648 | Values: 336.8 355.1 369.4 378.8 398.5 14477.7 14867.9 15449.8 19288.7 19811.4 18414.7 19164.9 19405.0 19495.0 19675.0 5043.3 5320.8 5325.6 5334.6 5346.1 4965.7 5260.6 5467.6 5793.6 5860.6 5807.7 5808.0 5814.7 5825.1 5830.9 5543.4 5787.7 5817.8 5889.9 5934.8 5493.0 5631.0 5720.0 5723.1 5750.6 12718.9 12760.7 12835.1 12877.2 13078.6 2607.1 2632.7 2634.5 2640.7 2717.2 9679.2 9710.3 10228.5 13145.2 13494.0 1795.7 1797.1 1797.1 1814.6 1819.7 108453.5 113459.4 113876.0 114114.0 115564.0 9310.0 11229.3 11376.9 11552.2 11591.8 14385.0 14393.9 14402.7 14418.9 14454.9 7992.7 8128.3 8137.8 8244.4 8267.1 7598.2 7867.4 7928.6 8058.5 8092.6 3626.7 4376.8 4472.2 4510.5 4523.4 1846.8 1918.1 1930.2 1933.3 1934.5 935.1 943.2 945.8 956.7 962.8 11488.4 11772.5 11790.7 12154.3 18179.6 407.7 427.2 439.2 451.1 458.2 4852.2 5767.3 5829.9 5835.2 6028.3 530666.0 535421.0 546538.0 547957.0 551334.0 129914.0 131121.0 131254.0 133276.0 133367.0 445.5 450.5 453.6 453.9 454.6 8144.0 10622.4 10943.6 10944.6 11008.5 43752.0 45983.5 51704.0 53057.2 54395.0 75959.6 76042.2 76773.6 77007.2 77808.0 11402.1 11691.7 12068.4 12139.4 12151.9 12979.4 13153.0 13207.7 13666.1 13703.9 9962.9 10244.6 11141.4 11722.9 11841.8 11234.5 11250.9 11395.9 11456.4 11686.2 9495.0 11703.3 11912.9 12043.9 12051.5 10324.8 11555.5 11674.4 11736.9 11980.8 11266.4 11541.8 11600.6 11765.9 11790.0 12:40:34 INFO - PROCESS | 1648 | 12:40:34 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5287.48 Median:2854.89 stddev:2917.67 (102.2%) stddev-sans-first:2910.43 12:40:34 INFO - PROCESS | 1648 | Values: 1295.1 1381.3 1384.2 1405.0 1407.5 7328.2 7706.3 7964.0 8215.7 8218.0 8036.7 8122.0 8129.1 8136.7 8141.8 2042.8 2112.8 2273.6 2310.0 2315.2 2264.7 2342.9 2347.2 2360.3 2366.8 2280.6 2299.8 2307.8 2309.0 2316.3 2297.3 2298.0 2322.3 2323.3 2324.1 1903.0 2238.2 2317.8 2332.7 2338.2 8157.8 8253.8 8255.6 8261.5 8265.5 4959.2 5054.3 5089.9 5126.3 5172.4 2789.5 3315.0 3517.8 3519.8 3522.4 1032.8 1207.7 1239.7 1254.6 1266.6 10619.7 10722.5 10734.4 10739.7 10753.0 4158.3 4366.8 4514.4 4602.8 4603.7 9212.4 9221.3 9245.8 9247.8 9275.7 5058.7 5095.6 5096.4 5105.4 5116.7 3712.8 4188.1 4452.6 4718.5 4721.4 3726.1 3736.3 3831.0 3855.5 3855.7 1245.5 1258.9 1263.9 1270.7 1270.8 767.5 770.1 777.4 791.2 796.1 4386.8 5404.2 5473.3 5508.9 5587.7 537.4 549.9 556.4 562.1 566.4 3123.9 3716.8 3780.1 3798.0 3838.7 9459.9 9681.3 9784.6 9815.7 9821.6 9655.9 9683.2 9688.3 9795.1 9845.0 7572.7 7637.2 7722.9 7735.5 7746.1 5409.9 5419.5 5461.2 5484.3 5497.3 8961.6 9036.2 9099.1 9102.0 9110.9 8572.1 9129.7 9140.8 9194.6 9212.6 6461.1 6628.7 6666.9 6949.3 7100.0 7656.6 7749.6 7835.6 7840.8 7851.8 6641.2 6720.8 6746.3 6787.5 6880.1 6872.6 6880.9 6897.7 6984.7 7000.1 6219.4 6284.5 6384.0 6402.1 6411.0 4423.7 4431.2 4488.4 4504.3 4579.2 4627.1 4785.1 4815.1 4876.5 4915.8 12:40:34 INFO - PROCESS | 1648 | 12:40:34 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4472.25 Median:5025.23 stddev:1382.37 (27.5%) stddev-sans-first:1351.23 12:40:34 INFO - PROCESS | 1648 | Values: 352.9 363.0 365.3 371.2 372.7 4715.5 5335.4 5337.9 5353.3 5406.4 5123.4 5253.6 5290.4 5293.6 5307.9 3351.4 3396.8 3750.4 3807.7 4108.5 3242.6 3356.4 3386.4 4086.0 4117.9 4131.4 4134.3 4138.0 4141.3 4165.0 3863.7 3948.5 4023.3 4120.6 4162.9 3150.4 3629.6 3629.7 3633.6 3651.7 5158.3 5161.3 5182.4 5182.8 5192.6 4605.5 4703.8 4744.0 4756.4 4786.9 5117.6 5555.7 5570.9 5603.4 5628.4 3524.3 3588.8 3731.9 4048.7 4056.2 5514.5 5824.4 5848.0 5934.6 5941.6 3364.8 3835.0 3845.3 3846.0 3858.8 3631.9 3883.8 3893.0 3908.8 3927.9 3936.5 3985.3 4295.0 4348.6 4504.1 5037.5 5514.3 5580.6 5585.7 5614.9 1254.6 1258.7 1265.4 1271.8 1278.2 751.9 781.8 798.7 849.3 865.3 4391.6 4395.6 4938.4 4942.7 4953.7 5049.6 5436.9 5446.0 5464.4 5487.9 4413.8 4471.4 4631.7 4918.3 4942.6 2464.6 2632.9 2636.7 2931.3 2956.9 4709.2 5294.9 5752.2 5767.2 5774.5 4971.4 4990.6 5301.8 5355.4 5704.5 4977.7 5306.1 5472.8 5744.5 5778.7 4854.0 5006.6 5384.4 5500.8 5529.7 5297.0 5478.9 5710.4 5721.1 5753.2 5024.4 5294.8 5993.8 5997.9 6012.8 3368.9 3863.8 3888.3 3936.9 3961.8 5126.4 5778.8 5817.7 5835.6 5836.0 4905.7 5026.0 5486.6 5580.4 5687.6 4732.6 5431.9 5539.4 5659.6 5710.2 5506.0 5513.9 5517.6 5521.8 5594.9 5616.2 5623.1 5623.3 5664.6 5677.1 4336.6 5092.0 5386.4 5470.3 5611.8 12:40:34 INFO - PROCESS | 1648 | 12:40:34 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:20796.10 Median:558.93 stddev:31509.35 (5637.4%) stddev-sans-first:31561.60 12:40:34 INFO - PROCESS | 1648 | Values: 696.7 712.9 752.4 786.6 800.9 14331.9 15434.6 15472.8 15486.0 15491.5 13930.5 14140.9 14144.3 14148.9 14173.9 685.9 703.0 704.5 834.5 836.8 862.7 868.3 882.9 884.5 886.1 936.1 940.2 940.3 942.9 943.8 719.1 741.4 929.0 932.3 933.5 558.9 558.9 561.1 659.4 678.1 14642.0 14715.2 14729.7 14733.2 14734.9 12306.7 12345.6 12687.4 12762.6 12859.2 10504.4 12327.1 12599.7 12749.1 13048.8 4377.6 4399.8 4423.4 4430.7 4581.5 110000.4 115241.7 116182.4 116311.0 116685.8 7684.6 8817.3 9043.7 9180.6 9273.2 17568.4 17821.8 18149.5 18262.5 18429.0 6519.7 7367.1 7430.3 7542.2 7681.1 4098.2 4132.9 4242.4 4249.7 4251.2 3266.4 3336.1 3348.2 3379.6 3381.8 643.6 645.6 652.3 658.9 659.0 551.4 565.3 576.2 596.6 623.8 9046.6 9155.8 9237.7 9395.2 9495.2 627.6 639.9 654.9 659.6 660.8 3517.0 4250.8 4390.2 4408.7 4488.7 104227.0 105232.0 108402.0 110169.4 113330.4 67953.0 92066.0 93930.0 95258.0 95282.5 82360.0 83798.0 83860.0 84194.6 86021.6 8440.2 8700.0 8704.7 8721.0 8970.2 44258.8 55480.0 56133.7 56559.7 56854.4 73984.3 74034.6 74867.6 75565.0 75719.6 11388.8 11486.8 11513.0 11586.8 12027.6 12485.6 12519.2 12777.6 12800.4 12836.5 11667.9 12019.9 12094.2 12121.8 12477.1 11721.7 11947.6 12042.8 12059.2 12552.3 11643.7 11822.3 12120.3 12201.0 12442.2 9275.4 9900.3 9930.4 10260.5 10289.2 9136.5 9255.8 9809.0 9846.1 9891.4 12:40:34 INFO - PROCESS | 1648 | -------- Summary: end -------- 12:40:34 INFO - PROCESS | 1648 | 12:40:35 INFO - TEST-INFO | 1648: exit 0 12:40:35 INFO - TEST-OK | dromaeo_css | took 1118924ms 12:40:35 INFO - TEST-START | kraken 12:40:35 INFO - operating with platform_type : mac_ 12:40:35 INFO - Initialising browser for kraken test... 12:40:35 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmpd9ViEh/profile http://localhost:49243/getInfo.html) 12:40:35 INFO - PROCESS | 1655 | 1483216835591 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 12:40:37 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 12:40:37 INFO - PROCESS | 1655 | colorDepth:24 12:40:37 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 12:40:37 INFO - PROCESS | 1655 | __metrics 12:40:37 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:40:37 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:40:38 INFO - TEST-INFO | 1655: exit 0 12:40:38 INFO - Browser initialized. 12:40:38 INFO - Running cycle 1/1 for kraken test... 12:40:38 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmpd9ViEh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:40:50 INFO - PROCESS | 1656 | RSS: Main: 233132032 12:40:50 INFO - PROCESS | 1656 | 12:40:53 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 12:40:54 INFO - PROCESS | 1656 | RSS: Main: 286269440 12:40:54 INFO - PROCESS | 1656 | 12:40:56 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 12:40:57 INFO - PROCESS | 1656 | RSS: Main: 286126080 12:40:57 INFO - PROCESS | 1656 | 12:41:00 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 12:41:01 INFO - PROCESS | 1656 | RSS: Main: 379310080 12:41:01 INFO - PROCESS | 1656 | 12:41:03 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 12:41:03 INFO - PROCESS | 1656 | RSS: Main: 280576000 12:41:03 INFO - PROCESS | 1656 | 12:41:05 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:41:06 INFO - PROCESS | 1656 | RSS: Main: 285483008 12:41:06 INFO - PROCESS | 1656 | 12:41:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 12:41:09 INFO - PROCESS | 1656 | RSS: Main: 326819840 12:41:09 INFO - PROCESS | 1656 | 12:41:12 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 12:41:13 INFO - PROCESS | 1656 | RSS: Main: 328773632 12:41:13 INFO - PROCESS | 1656 | 12:41:16 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 12:41:16 INFO - PROCESS | 1656 | RSS: Main: 328847360 12:41:16 INFO - PROCESS | 1656 | 12:41:18 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:41:19 INFO - PROCESS | 1656 | RSS: Main: 304332800 12:41:19 INFO - PROCESS | 1656 | 12:41:21 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:41:21 INFO - PROCESS | 1656 | RSS: Main: 307961856 12:41:21 INFO - PROCESS | 1656 | 12:41:23 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:41:24 INFO - PROCESS | 1656 | RSS: Main: 309166080 12:41:24 INFO - PROCESS | 1656 | 12:41:27 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:41:28 INFO - PROCESS | 1656 | RSS: Main: 320765952 12:41:28 INFO - PROCESS | 1656 | 12:41:31 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:41:31 INFO - PROCESS | 1656 | RSS: Main: 319123456 12:41:31 INFO - PROCESS | 1656 | 12:41:33 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 12:41:33 INFO - PROCESS | 1656 | RSS: Main: 363343872 12:41:33 INFO - PROCESS | 1656 | 12:41:33 INFO - PROCESS | 1656 | __start_tp_report 12:41:33 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 12:41:33 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 12:41:33 INFO - PROCESS | 1656 | |i|pagename|runs| 12:41:33 INFO - PROCESS | 1656 | |0;ai-astar;112;112;113;120;95;157;165;165;114;111 12:41:33 INFO - PROCESS | 1656 | |1;audio-beat-detection;139;115;139;178;112;110;109;108;129;108 12:41:33 INFO - PROCESS | 1656 | |2;audio-dft;195;265;198;185;184;180;186;182;184;185 12:41:33 INFO - PROCESS | 1656 | |3;audio-fft;79;58;59;90;58;59;58;58;76;59 12:41:33 INFO - PROCESS | 1656 | |4;audio-oscillator;83;71;85;69;70;68;83;69;69;84 12:41:33 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;89;85;87;85;87;85;86;83;87;83 12:41:33 INFO - PROCESS | 1656 | |6;imaging-darkroom;95;96;97;97;95;97;96;96;96;95 12:41:33 INFO - PROCESS | 1656 | |7;imaging-desaturate;85;85;87;87;86;87;87;85;86;86 12:41:33 INFO - PROCESS | 1656 | |8;json-parse-financial;56;56;53;52;54;55;56;54;54;55 12:41:33 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;65;74;75;72;72;68;93;101;103;69 12:41:33 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;92;87;86;88;87;89;90;87;90;91 12:41:33 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;139;137;139;139;142;150;148;143;261;171 12:41:33 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;176;173;170;171;172;168;172;172;171;171 12:41:33 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;67;67;67;68;66;67;67;65;65;67 12:41:33 INFO - PROCESS | 1656 | __end_tp_report 12:41:33 INFO - PROCESS | 1656 | __start_cc_report 12:41:33 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1969 12:41:33 INFO - PROCESS | 1656 | __end_cc_report 12:41:33 INFO - PROCESS | 1656 | __startTimestamp1483216893865__endTimestamp 12:41:33 INFO - PROCESS | 1656 | 12:41:33 INFO - PROCESS | 1656 | ------- Summary: start ------- 12:41:33 INFO - PROCESS | 1656 | Number of tests: 14 12:41:33 INFO - PROCESS | 1656 | 12:41:33 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:126.40 Median:138.50 stddev:25.67 (18.5%) stddev-sans-first:26.69 12:41:33 INFO - PROCESS | 1656 | Values: 112.0 112.0 113.0 120.0 95.0 157.0 165.0 165.0 114.0 111.0 12:41:33 INFO - PROCESS | 1656 | 12:41:33 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:124.70 Median:122.00 stddev:22.47 (18.4%) stddev-sans-first:23.23 12:41:33 INFO - PROCESS | 1656 | Values: 139.0 115.0 139.0 178.0 112.0 110.0 109.0 108.0 129.0 108.0 12:41:33 INFO - PROCESS | 1656 | 12:41:33 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:194.40 Median:185.50 stddev:25.43 (13.7%) stddev-sans-first:26.98 12:41:33 INFO - PROCESS | 1656 | Values: 195.0 265.0 198.0 185.0 184.0 180.0 186.0 182.0 184.0 185.0 12:41:33 INFO - PROCESS | 1656 | 12:41:33 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:65.40 Median:59.00 stddev:11.76 (19.9%) stddev-sans-first:11.40 12:41:33 INFO - PROCESS | 1656 | Values: 79.0 58.0 59.0 90.0 58.0 59.0 58.0 58.0 76.0 59.0 12:41:33 INFO - PROCESS | 1656 | 12:41:33 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:75.10 Median:77.00 stddev:7.50 (9.7%) stddev-sans-first:7.40 12:41:33 INFO - PROCESS | 1656 | Values: 83.0 71.0 85.0 69.0 70.0 68.0 83.0 69.0 69.0 84.0 12:41:33 INFO - PROCESS | 1656 | 12:41:33 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:86.50 stddev:1.89 (2.2%) stddev-sans-first:1.58 12:41:33 INFO - PROCESS | 1656 | Values: 89.0 85.0 87.0 85.0 87.0 85.0 86.0 83.0 87.0 83.0 12:41:33 INFO - PROCESS | 1656 | 12:41:33 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:96.00 stddev:0.82 (0.9%) stddev-sans-first:0.78 12:41:33 INFO - PROCESS | 1656 | Values: 95.0 96.0 97.0 97.0 95.0 97.0 96.0 96.0 96.0 95.0 12:41:33 INFO - PROCESS | 1656 | 12:41:33 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:86.10 Median:86.50 stddev:0.88 (1.0%) stddev-sans-first:0.83 12:41:33 INFO - PROCESS | 1656 | Values: 85.0 85.0 87.0 87.0 86.0 87.0 87.0 85.0 86.0 86.0 12:41:33 INFO - PROCESS | 1656 | 12:41:33 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.35 (2.5%) stddev-sans-first:1.32 12:41:33 INFO - PROCESS | 1656 | Values: 56.0 56.0 53.0 52.0 54.0 55.0 56.0 54.0 54.0 55.0 12:41:33 INFO - PROCESS | 1656 | 12:41:33 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:79.20 Median:72.00 stddev:14.19 (19.7%) stddev-sans-first:14.09 12:41:33 INFO - PROCESS | 1656 | Values: 65.0 74.0 75.0 72.0 72.0 68.0 93.0 101.0 103.0 69.0 12:41:33 INFO - PROCESS | 1656 | 12:41:33 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:89.50 stddev:2.00 (2.2%) stddev-sans-first:1.73 12:41:33 INFO - PROCESS | 1656 | Values: 92.0 87.0 86.0 88.0 87.0 89.0 90.0 87.0 90.0 91.0 12:41:33 INFO - PROCESS | 1656 | 12:41:33 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:156.90 Median:145.50 stddev:37.91 (26.1%) stddev-sans-first:39.65 12:41:33 INFO - PROCESS | 1656 | Values: 139.0 137.0 139.0 139.0 142.0 150.0 148.0 143.0 261.0 171.0 12:41:33 INFO - PROCESS | 1656 | 12:41:33 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.60 Median:172.00 stddev:2.07 (1.2%) stddev-sans-first:1.45 12:41:33 INFO - PROCESS | 1656 | Values: 176.0 173.0 170.0 171.0 172.0 168.0 172.0 172.0 171.0 171.0 12:41:33 INFO - PROCESS | 1656 | 12:41:33 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:0.97 (1.4%) stddev-sans-first:1.01 12:41:33 INFO - PROCESS | 1656 | Values: 67.0 67.0 67.0 68.0 66.0 67.0 67.0 65.0 65.0 67.0 12:41:33 INFO - PROCESS | 1656 | -------- Summary: end -------- 12:41:33 INFO - PROCESS | 1656 | 12:41:34 INFO - TEST-INFO | 1656: exit 0 12:41:34 INFO - TEST-OK | kraken | took 59681ms 12:41:34 INFO - SUITE-END | took 1178s 12:41:35 INFO - Completed test suite (00:19:38) 12:41:35 INFO - javascript benchmark 12:41:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3327.118313646884, 3333.49997500375, 3352.949705754414, 3357.8153201573905, 3416.812075335857, 5572.637778544402, 5637.943620563793, 5651.463111004453, 5731.971340143298, 5759.654420734753, 5647.802326918559, 5684.801031963882, 5711.543076553876, 5718.9428105718935, 5726.79956201532, 5163.561097306733, 5180.974095129519, 5197.896042079156, 5224.138017227153, 5234.241035049916, 5166.974165129169, 5279.340082489689, 5287.048331300364, 5311.654742441749, 5328.893422131555, 5270.81552145675, 5285.497877701612, 5296.655717378378, 5302.2841916341185, 5340.439253878345, 5105.081085404626, 5159.71621560814, 5177.534021938043, 5231.581473482112, 5259.5529380002745, 5146.150885103953, 5184.8963020739375, 5186.325777648919, 5195.584353251731, 5283.365996080456, 5706.28671416073, 5724.39893811148, 5725.6278341908055, 5733.827985160452, 5749.511291540224, 5425.6202065855405, 6053.576249662526, 6249.406306400884, 6259.155014073087, 6266.122742816009, 6149.739303442783, 6157.014877619604, 6240.500759939194, 6241.3446588108045, 6276.686165691697, 5756.740946657411, 5813.709314534341, 5825.766969321265, 5856.414358564152, 5869.090291004901, 6228.657423841732, 6296.43332100104, 6297.874042519124, 6309.936900631027, 6311.0, 5901.734421950937, 5946.643201407929, 5953.672548009901, 6156.168917196122, 6189.752409903644, 6168.25980882297, 6208.379162083756, 6226.221722284715, 6279.529035322369, 6290.150829638032, 6065.54508411862, 6100.115483254903, 6108.13739084631, 6129.233845769279, 6130.203073600493, 5003.224500202475, 6139.216166136444, 6184.226971628546, 6197.659128747872, 6208.751649933952, 4840.419149702058, 4911.8280860169725, 4912.140375434285, 4922.990786888679, 4953.6037117030555, 4400.823967041282, 4440.245158323093, 4466.9106617867465, 4483.708558943724, 4502.032063106382, 4829.420469543677, 5778.682172480554, 5799.18811366409, 5799.797007104731, 5803.88392232153, 6187.566870319124, 6196.132541444202, 6216.191895053614, 6240.781572645026, 6298.244210694655, 5684.516816070598, 5782.0, 5834.620749651259, 5844.064949608042, 5876.794312199053, 5318.25544423781, 5335.839924802262, 5395.946040539544, 5403.648762830403, 5412.242286079952, 6223.564350495512, 6230.626162430269, 6270.933941230001, 6271.090691849748, 6347.619142851444, 6256.906146407717, 6270.090836828543, 6300.306966233802, 6308.653024083719, 6319.715612797527, 6277.1525844010475, 6285.0, 6285.685715714288, 6301.464375528131, 6307.432331090224, 6054.122152287806, 6112.083187521729, 6197.9690101551005, 6229.2524897012645, 6237.5321850860455, 5155.510226528474, 6188.44304012641, 6213.161223234986, 6308.684565771601, 6313.747450101944, 6136.2636483622255, 6150.7847225346895, 6153.784617538365, 6162.661053641913, 6165.445109940127, 5777.595568310178, 6085.939140608714, 6099.146119543178, 6147.139400483847, 6158.168647232746, 5734.856628584319, 5889.146073819358, 5896.852578685395, 5934.376890426615, 6044.274687037584, 5906.616069955439, 6150.04674275488, 6154.107654390178, 6159.1069294951085, 6166.969165154145, 5743.368229494836, 5816.94183058164, 5844.6785426801935, 5859.472647461749, 5894.941050589439], "value": 5681.254002445338, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.5306427205122, 486.2099088980407, 500.859759267405, 502.96982181069137, 505.95699365553924, 30151.547726784087, 31662.366752664933, 31709.68290317099, 31710.99999999997, 39168.0, 39330.60669393312, 41489.7925510372, 41516.0, 41535.0, 41695.0, 9244.260459163252, 9446.716598502022, 9463.432194068344, 9467.857982130274, 9476.336656434052, 8448.281896038814, 8658.0, 9291.814163716721, 9396.81206375872, 9408.952955235249, 8797.912020879805, 9449.81100377992, 11458.942705286461, 11640.18518703691, 11642.30146191227, 11271.154663400277, 11351.091912647009, 11364.772704545863, 11370.431478426128, 11372.37451940143, 8368.372372072057, 8553.914460855374, 10940.288881222774, 11137.05335046522, 11214.0, 37507.24985500302, 39189.41215881764, 39200.41199382012, 39219.0, 39357.80321098405, 3949.2299001694682, 3957.7823219722904, 3966.940495892564, 3986.4618276532588, 4090.386442033689, 24516.87741561281, 30446.15514425958, 30642.0, 30643.540346894813, 30723.231919201975, 3129.9687003129907, 3136.5765621641012, 3295.0938491914676, 3341.398548261312, 3346.4311067118647, 338594.30702846573, 339871.0, 340886.0, 341359.0, 342972.0, 54679.72660136674, 57175.0, 57263.427365726646, 57381.42618573761, 57689.13466298009, 58981.705091474265, 60883.0, 61034.084488732704, 61049.08426373608, 61150.38849611536, 14958.551243462714, 15482.148481833607, 15553.377864885504, 15553.611159721098, 15597.298121584556, 11955.940220299017, 12015.939920300343, 12027.21823081497, 12027.21823081497, 12037.759244815055, 13981.23103229332, 14333.283335833166, 15650.765238521431, 15980.121093339745, 16020.599485012968, 5537.446255374511, 6501.707423165969, 6574.769883054166, 6628.602283862887, 6679.3320667933785, 864.7967727584013, 866.6836604639387, 876.4784952952983, 879.8856148700628, 897.228383590112, 29194.270143246165, 29228.26929326784, 29549.852250738608, 29952.0, 30412.087637370914, 1038.2472707287156, 1040.776233109885, 1068.3643232276784, 1076.9730756730987, 1155.1682788392345, 13882.51411200603, 14393.496227631982, 14622.780658290134, 15182.392704291704, 15392.008243418517, 572471.0, 575330.0, 578460.0, 579008.0, 579316.0, 49884.50115498871, 57557.0, 57643.0, 57701.0, 57728.0, 1381.9654508637163, 1401.7336706025822, 1415.709779495201, 1422.096968425037, 1422.4310275889727, 26318.736812632396, 29003.12990610285, 29483.0, 29530.852345738134, 29611.407771844013, 47363.76318118387, 48823.5117648819, 51789.7410512945, 51790.741046294526, 51921.0, 559530.0, 563983.0, 564669.0, 564963.0, 565491.0, 19612.215511379385, 24461.755382445946, 24492.6326105105, 24515.877420613495, 24554.877225614473, 33897.830510847285, 40262.0, 42144.578554214066, 42476.0, 43591.58942979765, 35097.29805403929, 35146.64853351434, 35280.47079293864, 36167.638323616426, 36315.0, 34681.47977780285, 34798.9560313191, 35350.46974295336, 35827.10432239215, 35917.461238081974, 33047.834760827005, 33071.50392744159, 33827.154321141184, 34284.31431371309, 34332.82833585816, 30382.544261835646, 31683.20791980219, 31750.52374214435, 31820.5226921601, 31913.202169945936, 20478.795212048284, 23530.647040294753, 23595.29214123579, 23867.0, 23982.88008559946], "value": 18929.29022175311, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.82653433481755, 355.1122194513716, 369.3978814532312, 378.79923640470554, 398.5038626909498, 14477.710445791077, 14867.851321486782, 15449.768253476206, 19288.710669339973, 19811.40565783025, 18414.72377914329, 19164.90417547914, 19405.0, 19495.0, 19675.0, 5043.344365232524, 5320.840374788762, 5325.627206095575, 5334.626576139672, 5346.064438723227, 4965.7020578765205, 5260.605454590902, 5467.644603100805, 5793.565482588801, 5860.560457965648, 5807.709614519278, 5808.0, 5814.651120932736, 5825.135956493924, 5830.912536311958, 5543.390227075019, 5787.71061446926, 5817.767289308423, 5889.941100588982, 5934.762609495615, 5492.972535137339, 5631.042722737145, 5719.971400143014, 5723.141528770676, 5750.6262092963825, 12718.936405317914, 12760.680982975407, 12835.101542892002, 12877.227366358049, 13078.607641770763, 2607.087519368225, 2632.697239817426, 2634.5257853586345, 2640.696319923195, 2717.184844546648, 9679.225661947028, 9710.271729620306, 10228.488575571191, 13145.211287322696, 13494.0, 1795.703708888032, 1797.0924683035114, 1797.1014492753623, 1814.5645045189192, 1819.654265689528, 108453.45773271163, 113459.43270283595, 113876.0, 114114.0, 115564.0, 9309.953450232706, 11229.3262404256, 11376.886231137583, 11552.249103808226, 11591.76816463683, 14385.0, 14393.92803035978, 14402.711945761026, 14418.855811441961, 14454.85545144556, 7992.680292788224, 8128.349732021424, 8137.75586732399, 8244.381671374671, 8267.131951145164, 7598.164201937782, 7867.409944254204, 7928.643211055516, 8058.476199047043, 8092.595370231465, 3626.673599376016, 4376.803043863034, 4472.1502914446155, 4510.50384457716, 4523.36672865799, 1846.8060853610177, 1918.1176658736858, 1930.2338360400358, 1933.2750218668, 1934.458351661537, 935.1396715022196, 943.1610851337523, 945.7824700318965, 956.7368973532334, 962.8122516109291, 11488.368139752394, 11772.52909883595, 11790.705232369259, 12154.270743755404, 18179.63640727178, 407.68811858927944, 427.1852358439168, 439.2379222049741, 451.0775464175703, 458.21873705332996, 4852.223644216909, 5767.336756273083, 5829.88340233193, 5835.153902684087, 6028.276606807123, 530666.0, 535421.0, 546538.0, 547957.0, 551334.0, 129914.0, 131121.0, 131254.0, 133276.0, 133367.0, 445.54554354558815, 450.5359479735877, 453.5713750505806, 453.92737162053913, 454.5908682186059, 8144.0, 10622.415767132881, 10943.616973405893, 10944.56221751153, 11008.504617292401, 43752.0, 45983.54016459793, 51704.0, 53057.20414193713, 54395.0, 75959.62020189864, 76042.23957760351, 76773.61613191899, 77007.22992770001, 77808.0, 11402.144839136932, 11691.707707307385, 12068.396580171131, 12139.39303034827, 12151.878481215075, 12979.35103244815, 13153.01352398593, 13207.66980825487, 13666.11170273929, 13703.862961370258, 9962.900370996198, 10244.565760793346, 11141.387223702774, 11722.944934955894, 11841.82237266459, 11234.528403751161, 11250.943745281222, 11395.943020284845, 11456.369899655265, 11686.18196726221, 9495.0, 11703.2978021319, 11912.880871191524, 12043.879561204276, 12051.517939282681, 10324.761028676607, 11555.4800033997, 11674.357910315015, 11736.941315293368, 11980.760384792082, 11266.380349080551, 11541.769164616493, 11600.593979210687, 11765.941065304163, 11790.0], "value": 8636.862070391384, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1295.0546101346024, 1381.3300549233622, 1384.224834092908, 1404.97892531612, 1407.5297852269266, 7328.230535793738, 7706.344960678349, 7964.044314682246, 8215.71245006425, 8217.958910205441, 8036.678532858678, 8121.959390203056, 8129.146439623828, 8136.674533018687, 8141.837163256746, 2042.77529471758, 2112.767595564487, 2273.5793878132517, 2309.9884500577473, 2315.22439982606, 2264.739554951177, 2342.852002518762, 2347.213683416058, 2360.3155085025323, 2366.7869891709743, 2280.646499792535, 2299.7815207555254, 2307.792298693117, 2309.0, 2316.328264803205, 2297.276357947241, 2298.0233400804673, 2322.314917099443, 2323.303009097264, 2324.1400681747778, 1902.9533756433907, 2238.160689741347, 2317.8493397929083, 2332.685087513181, 2338.1933233034574, 8157.75526734199, 8253.83492330156, 8255.587220639005, 8261.545614991173, 8265.545395003272, 4959.156943319608, 5054.292399064134, 5089.923651145235, 5126.333576635051, 5172.353455818023, 2789.4839454700946, 3315.0, 3517.7713448626014, 3519.753617246794, 3522.3659741246565, 1032.8140934631763, 1207.6739280394331, 1239.6962744127748, 1254.5797157952097, 1266.5820279307898, 10619.681409557727, 10722.463876806207, 10734.355938643706, 10739.731506712316, 10752.999999999922, 4158.251514727378, 4366.847160349373, 4514.435695538058, 4602.7698615069785, 4603.677742558056, 9212.401193922373, 9221.262299016063, 9245.768855778524, 9247.76880577991, 9275.67535136267, 5058.721770302595, 5095.617828662865, 5096.413912400048, 5105.361829771278, 5116.697486131256, 3712.832922518493, 4188.10791516699, 4452.577005184502, 4718.4809670936165, 4721.386219791405, 3726.1057346236707, 3736.3274610570356, 3830.9808450958126, 3855.4602355670227, 3855.7108216883844, 1245.4893493667553, 1258.937053147339, 1263.911526193176, 1270.6950331920273, 1270.8284381608364, 767.54331172952, 770.1412924589043, 777.3975169243881, 791.2008871040176, 796.0646240667309, 4386.758728269912, 5404.189371594292, 5473.261109750212, 5508.91736623951, 5587.69267690281, 537.4169026606098, 549.8982688202695, 556.4407770190933, 562.0697745231648, 566.3854717631416, 3123.937521249608, 3716.7769933804057, 3780.0738818989525, 3797.9620203798163, 3838.731288809812, 9459.90540094604, 9681.273904457054, 9784.608615655436, 9815.705528834147, 9821.558029888673, 9655.9034409655, 9683.176929961031, 9688.32181747271, 9795.06946840049, 9845.0, 7572.697092116364, 7637.236276372319, 7722.922770772333, 7735.497192682457, 7746.147923728386, 5409.891802163856, 5419.485148910847, 5461.235427040138, 5484.341878974548, 5497.340319161726, 8961.596728147118, 9036.23192028685, 9099.090090990849, 9102.0, 9110.863337049817, 8572.099929507312, 9129.726108216764, 9140.817183656423, 9194.632214711604, 9212.585433655368, 6461.127747754181, 6628.7017084230365, 6666.899996499959, 6949.339812717783, 7100.0, 7656.617169141409, 7749.612519374122, 7835.647395867314, 7840.764777056697, 7851.803704907423, 6641.169853768279, 6720.798376048726, 6746.325367463214, 6787.490938179755, 6880.071190389233, 6872.553284036521, 6880.901325095894, 6897.7240910363025, 6984.720611175496, 7000.124984376953, 6219.440250377488, 6284.528660350401, 6383.96808015957, 6402.103705481143, 6410.967945160244, 4423.734575925326, 4431.246688062973, 4488.371627972021, 4504.346869703809, 4579.152856721517, 4627.074585083064, 4785.066911952136, 4815.085133824562, 4876.512348765095, 4915.827946021872], "value": 4240.634201127921, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.918828669406, 362.96370362963705, 365.32050386281526, 371.17413754396455, 372.6943905997082, 4715.481297057321, 5335.386430560487, 5337.893242135155, 5353.304070470843, 5406.351237851459, 5123.359580052494, 5253.6322457428, 5290.444503327153, 5293.6294459387855, 5307.867303317419, 3351.3632409842176, 3396.7961922284617, 3750.4374343848503, 3807.733458657888, 4108.548059713429, 3242.578464799573, 3356.429407000816, 3386.3904497190497, 4085.9795701021453, 4117.8764637060785, 4131.421600975866, 4134.338505839067, 4137.958620413802, 4141.343462614948, 4164.9583504164875, 3863.690904727629, 3948.5064366954275, 4023.2556976959354, 4120.587941205856, 4162.875113746592, 3150.385674793416, 3629.6007439181667, 3629.745917785756, 3633.6184700606586, 3651.6896063834606, 5158.329417175743, 5161.251618515332, 5182.404023537304, 5182.766775495112, 5192.61055420845, 4605.4933957264675, 4703.7883295251795, 4743.976280118612, 4756.429228492603, 4786.880327991793, 5117.641765076446, 5555.722213889289, 5570.86072848182, 5603.439656034405, 5628.380878103405, 3524.3303772283184, 3588.802615856127, 3731.9253614927547, 4048.67610591152, 4056.188762247533, 5514.531264842493, 5824.359320474744, 5847.970760146172, 5934.643921364732, 5941.554383421218, 3364.8149351785396, 3835.0, 3845.288621605012, 3846.0385503162406, 3858.8070596470066, 3631.910426871928, 3883.8446462141196, 3893.0, 3908.843646254118, 3927.9394563467704, 3936.527616686016, 3985.2826491231276, 4295.0, 4348.608625223745, 4504.144212599661, 5037.496250375007, 5514.255575497256, 5580.637258578179, 5585.720713964285, 5614.887702245932, 1254.5859866244118, 1258.660161756339, 1265.436880588148, 1271.7520083583797, 1278.1883503974857, 751.9097708275042, 781.8201813582908, 798.6845196147606, 849.2696281198165, 865.2515574028546, 4391.560843915647, 4395.648348132206, 4938.358013458227, 4942.728149951788, 4953.72754498506, 5049.606842047368, 5436.864078398072, 5446.0, 5464.377098992506, 5487.862803429946, 4413.801378937956, 4471.396361491157, 4631.652626053059, 4918.336024636628, 4942.57988071018, 2464.5705490815285, 2632.8551929644054, 2636.736326367348, 2931.2525306047246, 2956.911292661224, 4709.222978208626, 5294.92057619136, 5752.223449834302, 5767.163761254595, 5774.498630356054, 4971.4282857471135, 4990.575801056878, 5301.83359660874, 5355.410904800468, 5704.543636509153, 4977.701337919736, 5306.071437498423, 5472.808451704092, 5744.4829965303325, 5778.711064446761, 4853.975730121327, 5006.574441172541, 5384.353877534721, 5500.834974950758, 5529.695866727368, 5296.973515132399, 5478.891240963317, 5710.430306362071, 5721.05602575587, 5753.223314852441, 5024.4473107957365, 5294.788208471618, 5993.766482857779, 5997.91003134962, 6012.759489620541, 3368.8714280716235, 3863.787491687872, 3888.2806680764147, 3936.9015774605864, 3961.801909904551, 5126.410462796826, 5778.8266352009505, 5817.738201781015, 5835.649861008353, 5835.9708201458725, 4905.705657660552, 5026.0199261143725, 5486.588505862156, 5580.441955804549, 5687.601867869375, 4732.645051621073, 5431.864203394789, 5539.421264939634, 5659.575956546395, 5710.172025056427, 5505.981393442226, 5513.889722205614, 5517.586181036358, 5521.779128834802, 5594.943665842395, 5616.1856530803625, 5623.0721930880945, 5623.297087864017, 5664.575156863334, 5677.148427735872, 4336.566343365638, 5091.974540127276, 5386.384362237836, 5470.316210473691, 5611.80358687444], "value": 4041.0984543931813, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [696.7004188199076, 712.8823744082226, 752.428154602502, 786.6302837666296, 800.8678568036272, 14331.856681433183, 15434.61413464664, 15472.845271547281, 15486.0, 15491.5352539424, 13930.51243206488, 14140.929295353535, 14144.292785360742, 14148.858511414883, 14173.936954728386, 685.9416949559294, 703.0017375327047, 704.5138854190618, 834.4784509681449, 836.8493671139192, 862.6851199312238, 868.3053557154251, 882.8631562107884, 884.5488800711652, 886.1006078829993, 936.1013427109982, 940.2337095267369, 940.3323640215456, 942.9009953954838, 943.7923656795494, 719.0975325965932, 741.4328039050131, 928.9688445825128, 932.3240650528346, 933.4632586262858, 558.9105743081128, 558.9496945274944, 561.0714267886655, 659.3768888400464, 678.1184460201719, 14642.0, 14715.190664513448, 14729.70540589193, 14733.189674567895, 14734.926325368411, 12306.692332691664, 12345.62963111099, 12687.41875626387, 12762.617121486277, 12859.228446293158, 10504.369737815756, 12327.137100402975, 12599.748005039848, 12749.10756247063, 13048.804267935988, 4377.627901628365, 4399.802008909607, 4423.40284061654, 4430.689851710382, 4581.473130589991, 110000.4499977503, 115241.68028914678, 116182.41908790403, 116311.0, 116685.83314166835, 7684.615769211517, 8817.29461643067, 9043.728688139367, 9180.586873590704, 9273.211776998896, 17568.38510652121, 17821.821781782277, 18149.455516334532, 18262.54343641393, 18428.986405747546, 6519.706613202417, 7367.115946086396, 7430.294122058396, 7542.170361260257, 7681.078270607562, 4098.180363927227, 4132.855350062733, 4242.427272318259, 4249.702520823513, 4251.17102165081, 3266.428375034407, 3336.115929278743, 3348.2466445049668, 3379.5974670511955, 3381.7970921744773, 643.6009674002148, 645.6029541831833, 652.3183273479226, 658.8930596597665, 658.9901151482732, 551.4154995704566, 565.2679779685317, 576.2479963647396, 596.5704692621306, 623.8178651455557, 9046.638134474679, 9155.77110572241, 9237.676681316256, 9395.154436100784, 9495.192908602708, 627.5732501898661, 639.8528338482082, 654.9443297319685, 659.6299515708432, 660.8050625065617, 3516.9648303516637, 4250.787460627018, 4390.165868484977, 4408.6913916026215, 4488.730676159441, 104227.0, 105232.0, 108402.0, 110169.44915275533, 113330.43334783273, 67952.98070528946, 92066.0, 93930.0, 95258.0, 95282.52358738161, 82360.0, 83798.0, 83860.0, 84194.57902710447, 86021.56989215013, 8440.24037836573, 8699.956500217459, 8704.651813927625, 8720.956395217983, 8970.23752981004, 44258.77870610755, 55480.0, 56133.71933140308, 56559.71720141373, 56854.43145568491, 73984.26015739773, 74034.62982685052, 74867.62566187134, 75565.0, 75719.62140189264, 11388.829167562328, 11486.770264594828, 11513.021393181674, 11586.76826463483, 12027.579034734092, 12485.625431237077, 12519.248845069325, 12777.552785652457, 12800.35998200105, 12836.486540538273, 11667.941660291644, 12019.879801201876, 12094.153409261267, 12121.81817272724, 12477.126601137834, 11721.706957326134, 11947.641570752892, 12042.819357709466, 12059.15585908978, 12552.309622970824, 11643.650690479299, 11822.34977076269, 12120.33338166374, 12201.02391808671, 12442.191257568229, 9275.39709918853, 9900.25748068912, 9930.404175749478, 10260.486975651336, 10289.228307876789, 9136.451812891248, 9255.814883702424, 9808.950955245178, 9846.113849753556, 9891.35706179096], "value": 7178.559996480687, "unit": "score"}], "name": "dromaeo_css", "value": 6966.1263219856055, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 113.0, 120.0, 95.0, 157.0, 165.0, 165.0, 114.0, 111.0], "value": 126.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 115.0, 139.0, 178.0, 112.0, 110.0, 109.0, 108.0, 129.0, 108.0], "value": 124.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 265.0, 198.0, 185.0, 184.0, 180.0, 186.0, 182.0, 184.0, 185.0], "value": 194.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 59.0, 90.0, 58.0, 59.0, 58.0, 58.0, 76.0, 59.0], "value": 65.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 85.0, 69.0, 70.0, 68.0, 83.0, 69.0, 69.0, 84.0], "value": 75.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 87.0, 85.0, 87.0, 85.0, 86.0, 83.0, 87.0, 83.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 97.0, 97.0, 95.0, 97.0, 96.0, 96.0, 96.0, 95.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 87.0, 87.0, 86.0, 87.0, 87.0, 85.0, 86.0, 86.0], "value": 86.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 53.0, 52.0, 54.0, 55.0, 56.0, 54.0, 54.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 75.0, 72.0, 72.0, 68.0, 93.0, 101.0, 103.0, 69.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 87.0, 86.0, 88.0, 87.0, 89.0, 90.0, 87.0, 90.0, 91.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 137.0, 139.0, 139.0, 142.0, 150.0, 148.0, 143.0, 261.0, 171.0], "value": 156.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 173.0, 170.0, 171.0, 172.0, 168.0, 172.0, 172.0, 171.0, 171.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 68.0, 66.0, 67.0, 67.0, 65.0, 65.0, 67.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1471.3, "alertThreshold": 2.0}]} 12:41:35 INFO - Return code: 0 12:41:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:41:35 INFO - # TBPL SUCCESS # 12:41:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:41:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:41:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:41:35 INFO - 2016-12-31 12:41:35.154 screenresolution[1660:10210] starting screenresolution argv=screenresolution get 12:41:35 INFO - 2016-12-31 12:41:35.157 screenresolution[1660:10210] Display 0: 1600x1200x32@60 12:41:35 INFO - 2016-12-31 12:41:35.163 screenresolution[1661:10213] starting screenresolution argv=screenresolution list 12:41:35 INFO - Available Modes on Display 0 12:41:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:41:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:41:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:41:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:41:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:41:35 INFO - Intel Iris: 12:41:35 INFO - Chipset Model: Intel Iris 12:41:35 INFO - Type: GPU 12:41:35 INFO - Bus: Built-In 12:41:35 INFO - VRAM (Dynamic, Max): 1536 MB 12:41:35 INFO - Vendor: Intel (0x8086) 12:41:35 INFO - Device ID: 0x0a2e 12:41:35 INFO - Revision ID: 0x0009 12:41:35 INFO - Displays: 12:41:35 INFO - Display: 12:41:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:41:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:41:35 INFO - Display Serial Number: 1600x1200 60 12:41:35 INFO - Main Display: Yes 12:41:35 INFO - Mirror: Off 12:41:35 INFO - Online: Yes 12:41:35 INFO - Rotation: Supported 12:41:35 INFO - Return code: 0 12:41:35 INFO - Running post-action listener: _resource_record_post_action 12:41:35 INFO - [mozharness: 2016-12-31 20:41:35.403035Z] Finished run-tests step (success) 12:41:35 INFO - Running post-run listener: _resource_record_post_run 12:41:35 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 33336320; Write bytes: 550304768; Read time: 312; Write time: 1644 12:41:35 INFO - TinderboxPrint: CPU usage
53.6% 12:41:35 INFO - TinderboxPrint: I/O read bytes / time
33,336,320 / 312 12:41:35 INFO - TinderboxPrint: I/O write bytes / time
550,304,768 / 1,644 12:41:35 INFO - TinderboxPrint: CPU idle
2,229.1 (46.4%) 12:41:35 INFO - TinderboxPrint: CPU system
959.0 (20.0%) 12:41:35 INFO - TinderboxPrint: CPU user
1,617.7 (33.7%) 12:41:35 INFO - TinderboxPrint: Swap in / out
278,351,872 / 0 12:41:35 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 226892288; Write bytes: 221685248; Read time: 16435; Write time: 437 12:41:35 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 32394240; Write bytes: 298526208; Read time: 294; Write time: 1145 12:41:35 INFO - Running post-run listener: _upload_blobber_files 12:41:35 INFO - Blob upload gear active. 12:41:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:41:35 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:41:35 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'] 12:41:35 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 12:41:36 INFO - (blobuploader) - INFO - Open directory for files ... 12:41:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:41:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:41:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:41:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:41:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:41:37 INFO - (blobuploader) - INFO - Done attempting. 12:41:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:41:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:41:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:41:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:41:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:41:38 INFO - (blobuploader) - INFO - Done attempting. 12:41:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:41:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:41:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:41:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:41:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:41:39 INFO - (blobuploader) - INFO - Done attempting. 12:41:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:41:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:41:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:41:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:41:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:41:41 INFO - (blobuploader) - INFO - Done attempting. 12:41:41 INFO - (blobuploader) - INFO - Iteration through files over. 12:41:41 INFO - Return code: 0 12:41:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:41:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:41:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b16dcda4d64d4fbdb76f84d8532ea968c3e66f33753e929d31d5b48a3d96a339a8e136b40832c17b65bedcd3d55aae44fab32aee8b7f2769343f30eb2faf41d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8201afb87b753827ac5be8ef290666f8379925f196b17ee25858e4bd554142edf36ebd5132001e321a9557c6923732a01916b1b97a922176f530a6f8f87788a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c07613e2fe5ae554a0f6a5fb69f0b7779c59822b5e8e57fe06fe51b2a7613952863dd04ea52765770e5f60bf51eaa1b004de0c9ceff6aaff8ecbab25c2c53e9f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b24ebb7939e9214cfb0363cde8eecfe7da920b777a60d98a40210dd8381b660bbebc28b31152c703dcd01dfbc3f7df8a82012b62335b7d7bd58dfb47c952461"} 12:41:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:41:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:41:41 INFO - Contents: 12:41:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b16dcda4d64d4fbdb76f84d8532ea968c3e66f33753e929d31d5b48a3d96a339a8e136b40832c17b65bedcd3d55aae44fab32aee8b7f2769343f30eb2faf41d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8201afb87b753827ac5be8ef290666f8379925f196b17ee25858e4bd554142edf36ebd5132001e321a9557c6923732a01916b1b97a922176f530a6f8f87788a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c07613e2fe5ae554a0f6a5fb69f0b7779c59822b5e8e57fe06fe51b2a7613952863dd04ea52765770e5f60bf51eaa1b004de0c9ceff6aaff8ecbab25c2c53e9f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b24ebb7939e9214cfb0363cde8eecfe7da920b777a60d98a40210dd8381b660bbebc28b31152c703dcd01dfbc3f7df8a82012b62335b7d7bd58dfb47c952461"} 12:41:41 INFO - Running post-run listener: copy_logs_to_upload_dir 12:41:41 INFO - Copying logs to upload dir... 12:41:41 INFO - mkdir: /builds/slave/test/build/upload/logs 12:41:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.704437 ========= master_lag: 0.32 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-31 12:41:41.952196) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-31 12:41:41.959386) ========= 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.jaLxXKW56L/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.zwPx9kZVhX/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b16dcda4d64d4fbdb76f84d8532ea968c3e66f33753e929d31d5b48a3d96a339a8e136b40832c17b65bedcd3d55aae44fab32aee8b7f2769343f30eb2faf41d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8201afb87b753827ac5be8ef290666f8379925f196b17ee25858e4bd554142edf36ebd5132001e321a9557c6923732a01916b1b97a922176f530a6f8f87788a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c07613e2fe5ae554a0f6a5fb69f0b7779c59822b5e8e57fe06fe51b2a7613952863dd04ea52765770e5f60bf51eaa1b004de0c9ceff6aaff8ecbab25c2c53e9f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b24ebb7939e9214cfb0363cde8eecfe7da920b777a60d98a40210dd8381b660bbebc28b31152c703dcd01dfbc3f7df8a82012b62335b7d7bd58dfb47c952461"} build_url:https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010968 build_url: 'https://queue.taskcluster.net/v1/task/IgnNtvL6Qd6vsIZgeN6gsg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b16dcda4d64d4fbdb76f84d8532ea968c3e66f33753e929d31d5b48a3d96a339a8e136b40832c17b65bedcd3d55aae44fab32aee8b7f2769343f30eb2faf41d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8201afb87b753827ac5be8ef290666f8379925f196b17ee25858e4bd554142edf36ebd5132001e321a9557c6923732a01916b1b97a922176f530a6f8f87788a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c07613e2fe5ae554a0f6a5fb69f0b7779c59822b5e8e57fe06fe51b2a7613952863dd04ea52765770e5f60bf51eaa1b004de0c9ceff6aaff8ecbab25c2c53e9f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b24ebb7939e9214cfb0363cde8eecfe7da920b777a60d98a40210dd8381b660bbebc28b31152c703dcd01dfbc3f7df8a82012b62335b7d7bd58dfb47c952461"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-31 12:41:41.996739) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 12:41:41.997057) ========= 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.jaLxXKW56L/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.zwPx9kZVhX/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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.004970 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 12:41:42.051029) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-31 12:41:42.051356) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-31 12:41:42.051661) ========= ========= Total master_lag: 0.47 =========